Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.2022 22: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 16491152 kB
MemAvailable 31252144 kB
Buffers 0 kB
Cached 15173428 kB
SwapCached 2300 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 2022-01-13 22:38:01
DTS_END                         2022-01-13 22:38:01.7340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38572
TRN_ID                          240781
ATT_ID                          647
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: 9282.55 23986 1113906
customer order: creation 168.44 374 20213
customer order: refuse 14.89 336 1787
order to supplier: creation 45.85 1285 5502
order to supplier: removal 3.73 103592 447
invoice (draft): creation 44.52 3432 5342
invoice (draft): removal 54.67 809 6560
invoice accept: apply 41.40 18333 4968
invoice accept: cancel 49.19 742 5903
customer reserve: creation 3787.59 184 454511
customer reserve: removal 4764.64 64 571757
realization accept: apply 83.39 180 10007
realization accept: cancel 96.56 105 11587
payment from customer: creation 58.40 141 7008
payment from customer: removal 5.48 99 657
payment to supplier: creation 48.28 141 5794
payment to supplier: removal 3.55 57 426
service: total inventory turnovers 4.63 1329 555
service: total monetary turnovers 6.41 44 769
service: aggregate perf. data 0.91 20615 109
service: refresh index statistics 0.03 327851 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-01-2022 22:38:49 to 13-01-2022 22:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5562
TEST_TIME 2 4112
TEST_TIME 3 4863
TEST_TIME 4 4949
TEST_TIME 5 5186
TEST_TIME 6 5137
TEST_TIME 7 5376
TEST_TIME 8 5328
TEST_TIME 9 5944
TEST_TIME 10 5999
TEST_TIME 11 6059
TEST_TIME 12 6606
TEST_TIME 13 6946
TEST_TIME 14 7222
TEST_TIME 15 7219
TEST_TIME 16 7427
TEST_TIME 17 7757
TEST_TIME 18 7766
TEST_TIME 19 8246
TEST_TIME 20 8249
TEST_TIME 21 8129
TEST_TIME 22 8500
TEST_TIME 23 8750
TEST_TIME 24 8825
TEST_TIME 25 9062
TEST_TIME 26 9644
TEST_TIME 27 9872
TEST_TIME 28 10461
TEST_TIME 29 11186
TEST_TIME 30 11204
TEST_TIME 31 10974
TEST_TIME 32 10746
TEST_TIME 33 10549
TEST_TIME 34 10455
TEST_TIME 35 10424
TEST_TIME 36 10270
TEST_TIME 37 10294
TEST_TIME 38 10110
TEST_TIME 39 10102
TEST_TIME 40 9987
TEST_TIME 41 10047
TEST_TIME 42 9890
TEST_TIME 43 9811
TEST_TIME 44 9669
TEST_TIME 45 9686
TEST_TIME 46 9699
TEST_TIME 47 9621
TEST_TIME 48 9514
TEST_TIME 49 9519
TEST_TIME 50 9549
TEST_TIME 51 9597
TEST_TIME 52 9512
TEST_TIME 53 9374
TEST_TIME 54 9341
TEST_TIME 55 9274
TEST_TIME 56 9251
TEST_TIME 57 9229
TEST_TIME 58 9238
TEST_TIME 59 9214
TEST_TIME 60 9233
TEST_TIME 61 9364
TEST_TIME 62 9351
TEST_TIME 63 9356
TEST_TIME 64 9494
TEST_TIME 65 9485
TEST_TIME 66 9479
TEST_TIME 67 9548
TEST_TIME 68 9585
TEST_TIME 69 9589
TEST_TIME 70 9616
TEST_TIME 71 9609
TEST_TIME 72 9665
TEST_TIME 73 9593
TEST_TIME 74 9511
TEST_TIME 75 9435
TEST_TIME 76 9364
TEST_TIME 77 9410
TEST_TIME 78 9390
TEST_TIME 79 9359
TEST_TIME 80 9289
TEST_TIME 81 9228
TEST_TIME 82 9227
TEST_TIME 83 9254
TEST_TIME 84 9246
TEST_TIME 85 9232
TEST_TIME 86 9235
TEST_TIME 87 9274
TEST_TIME 88 9373
TEST_TIME 89 9364
TEST_TIME 90 9316
TEST_TIME 91 9385
TEST_TIME 92 9380
TEST_TIME 93 9386
TEST_TIME 94 9395
TEST_TIME 95 9366
TEST_TIME 96 9421
TEST_TIME 97 9456
TEST_TIME 98 9408
TEST_TIME 99 9363
TEST_TIME 100 9359
TEST_TIME 101 9305
TEST_TIME 102 9291
TEST_TIME 103 9257
TEST_TIME 104 9208
TEST_TIME 105 9194
TEST_TIME 106 9189
TEST_TIME 107 9215
TEST_TIME 108 9203
TEST_TIME 109 9208
TEST_TIME 110 9204
TEST_TIME 111 9220
TEST_TIME 112 9256
TEST_TIME 113 9303
TEST_TIME 114 9300
TEST_TIME 115 9300
TEST_TIME 116 9283
TEST_TIME 117 9328
TEST_TIME 118 9322
TEST_TIME 119 9361
TEST_TIME 120 9285

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-01-2022 22:38:53 to 13-01-2022 22:38:56.

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 1535721 1535721 0 0.00 0 44814 6 0 0
doc_list_biud 1535709 1535709 0 0.00 0 50659 30 0 0
sp_add_invoice_to_stock 5089 4968 121 2.37 33 164782 18334 0 121
sp_cancel_adding_invoice 5903 5903 0 0.00 5 61404 743 0 0
sp_cancel_client_order 1787 1787 0 0.00 4 15401 336 0 0
sp_cancel_customer_reserve 571757 571757 0 0.00 1 50668 64 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 3 15398 100 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 2 805 58 0 0
sp_cancel_supplier_invoice 6572 6560 12 0.18 2 25556 809 0 12
sp_cancel_supplier_order 447 447 0 0.00 6 1069221 103593 0 0
sp_cancel_write_off 11587 11587 0 0.00 1 22452 105 0 0
sp_client_order 20213 20213 0 0.00 3 50548 375 0 0
sp_customer_reserve 454511 454511 0 0.00 0 104501 184 0 0
sp_fill_shopping_cart 31054 31054 0 0.00 1 21171 43 0 0
sp_fill_shopping_cart_clo_res 454507 454507 0 0.00 0 21196 14 0 0
sp_get_clo_for_invoice 4963 4963 0 0.00 0 25492 332 0 0
sp_kill_cost_storno 18573 18573 0 0.00 0 13178 54 0 0
sp_kill_qstorno_ret_qs2qd 1162108 1162108 0 0.00 0 50650 35 0 0
sp_kill_qty_storno 613002 613002 0 0.00 0 50658 74 0 0
sp_lock_selected_doc 20811 20811 0 0.00 0 9145 4 0 0
sp_make_cost_storno 27772 27772 0 0.00 0 44803 58 0 0
sp_multiply_rows_for_pdistr 27761 27761 0 0.00 0 12537 30 0 0
sp_multiply_rows_for_qdistr 20213 20213 0 0.00 0 50537 172 0 0
sp_pay_from_customer 7008 7008 0 0.00 4 45406 141 0 0
sp_pay_to_supplier 5794 5794 0 0.00 5 14222 141 0 0
sp_payment_common 12800 12800 0 0.00 1 45402 27 0 0
sp_qd_handle_on_cancel_clo 1787 1787 0 0.00 0 9678 301 0 0
sp_qd_handle_on_invoice_upd_sts 10862 10862 0 0.00 0 20966 316 0 0
sp_qd_handle_on_reserve_upd_sts 10002 10002 0 0.00 0 21920 58 0 0
sp_reserve_write_off 10007 10007 0 0.00 6 22152 181 0 0
sp_supplier_invoice 5342 5342 0 0.00 5 93216 3433 0 0
sp_supplier_order 5502 5502 0 0.00 5 56882 1285 0 0
srv_aggregate_perf_data 109 109 0 0.00 2550 375888 20616 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2046 137658 46201 0 0
srv_find_qd_qs_mism 1067416 1067416 0 0.00 0 15049 5 0 0
srv_make_invnt_saldo 555 555 0 0.00 35 109993 1330 0 0
srv_make_money_saldo 769 769 0 0.00 1 12126 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 186429 522477 327852 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4969 4969 0 0.00 0 11872 177 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 3767 85 0 0
t$perf-norm:sp_cancel_client_order 1787 1787 0 0.00 0 6175 22 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 3525 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 8877 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 908 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 4434 19 0 0
t$perf-norm:sp_cancel_supplier_order 447 447 0 0.00 0 24593 1521 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 2310 10 0 0
t$perf-norm:sp_client_order 20213 20213 0 0.00 0 9019 18 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 612 5 0 0
t$perf-norm:sp_pay_from_customer 7008 7008 0 0.00 0 4858 10 0 0
t$perf-norm:sp_pay_to_supplier 5794 5794 0 0.00 0 26965 13 0 0
t$perf-norm:sp_reserve_write_off 10007 10007 0 0.00 0 4158 10 0 0
t$perf-norm:sp_supplier_invoice 5342 5342 0 0.00 0 8038 19 0 0
t$perf-norm:sp_supplier_order 5502 5502 0 0.00 0 5929 13 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 245 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1300 39 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 768 20 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 3239 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10158 9916 242 2.38 0 18809 27 0 242
v_all_customers 37078 37078 0 0.00 0 48 1 0 0
v_all_suppliers 30008 30008 0 0.00 0 93 1 0 0
v_all_wares 40426 40426 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 4114 11 0 0
v_cancel_client_order 3574 3574 0 0.00 0 15328 10 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 15312 17 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 1035 3 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 433 3 0 0
v_cancel_supplier_invoice 820 796 24 2.92 0 3762 22 0 24
v_cancel_supplier_order 894 894 0 0.00 0 468 4 0 0
v_cancel_write_off 2030 2030 0 0.00 0 2867 16 0 0
v_max_id_clo_ord 5502 5502 0 0.00 0 6575 3 0 0
v_max_id_clo_res 991 991 0 0.00 0 866 8 0 0
v_max_id_ord_sup 5339 5339 0 0.00 0 6695 4 0 0
v_max_non_paid_invoice 1631 1631 0 0.00 0 242 2 0 0
v_max_non_paid_realizn 3544 3544 0 0.00 0 2235 4 0 0
v_min_id_clo_ord 5502 5502 0 0.00 0 4796 10 0 0
v_min_id_clo_res 991 991 0 0.00 0 4584 7 0 0
v_min_id_ord_sup 5339 5339 0 0.00 0 18703 15 0 0
v_min_non_paid_invoice 5794 5794 0 0.00 0 5035 15 0 0
v_min_non_paid_realizn 7006 7006 0 0.00 0 45396 23 0 0
v_our_firm 10272 10272 0 0.00 0 19 1 0 0
v_reserve_write_off 20004 20004 0 0.00 0 17749 10 0 0
x_lock_depdocs_on_canc_invoice 12065 12065 0 0.00 0 14409 124 0 0
x_lock_depdocs_on_canc_sup_ord 447 447 0 0.00 0 7049 315 0 0
x_make_qstorno_1000_1200 5502 5502 0 0.00 1 56818 1203 0 0
x_make_qstorno_1200_2000 5342 5342 0 0.00 2 93197 3272 0 0
x_make_qstorno_2100_3300 454511 454511 0 0.00 0 104498 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2022 22:38:57 to 13-01-2022 22:39:01.

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
20:08:05 12 8192000000 8803658336 8816398336 7960752 0.097 2 2 4 0 8112416 66704 84960
20:09:06 39887 8192000000 9914006608 10228551680 994597008 10.826 100 87 173 1 1023589696 6369632 22623056
20:10:54 8756 8192000000 9957313072 10283925504 1043962992 11.303 100 87 174 0 1073930768 8355360 21612416
20:12:09 12057 8192000000 9978952592 10311028736 1064683264 11.501 100 86 172 0 1095486304 9524688 21278352
20:13:29 12278 8192000000 10028233776 10378764288 1121889904 12.045 100 77 154 0 1149887376 10237152 17760320
20:14:44 9738 8192000000 10099697280 10459574272 1179755328 12.588 100 84 168 0 1211285936 12617856 18912752
20:15:56 4870 8192000000 10136553792 10505887744 1225758128 13.015 100 80 156 2 1255815072 12759760 17297184
20:17:03 9434 8192000000 10162608288 10540171264 1249958496 13.238 100 69 138 0 1277843344 13550032 14334816
20:18:15 1433 8192000000 10199302048 10580201472 1281378064 13.526 100 95 190 1 1317268064 14724704 21165296
20:19:18 2225 8192000000 10218587456 10601750528 1297758672 13.675 100 94 188 1 1332679344 14025584 20895088
20:20:22 20240 8192000000 10209552352 10608353280 1307367184 13.762 100 44 87 1 1324368528 5601520 11399824
20:21:44 11302 8192000000 10198313136 10615537664 1316425232 13.844 100 21 42 0 1332279760 11109968 4744560
20:23:00 11874 8192000000 10208244464 10629767168 1324116192 13.914 100 18 36 0 1334542848 6680016 3746640
20:24:14 33877 8192000000 10212769808 10637291520 1325722640 13.928 100 32 64 3 1343028256 9858528 7447088
20:25:49 9735 8192000000 10226668736 10647519232 1332521008 13.990 100 29 58 2 1345999616 7097424 6381184
20:27:00 5970 8192000000 10220020752 10638032896 1315044048 13.832 99 36 72 1 1331199952 8032928 8122976
20:28:07 55771 8192000000 10212636512 10631839744 1305831872 13.748 99 39 77 1 1324014384 8881200 9301312
20:30:03 6711 8192000000 10165731024 10567069696 1218457824 12.947 95 24 48 1 1228747728 5069776 5220128
20:31:10 7824 8192000000 10140656224 10539159552 1226587952 13.023 98 19 38 0 1236186480 5307888 4290640
20:32:19 8914 8192000000 10149152640 10549559296 1233244688 13.084 98 28 57 1 1244933072 4941904 6746480
20:33:28 37599 8192000000 10176957824 10583244800 1279585472 13.509 100 43 86 0 1296441056 6774464 10081120
20:35:06 6166 8192000000 10190460016 10599981056 1294824352 13.648 100 30 60 0 1308645184 6775792 7045040
20:36:14 8381 8192000000 10208730720 10613125120 1303640016 13.728 100 20 40 2 1317280304 8993856 4646432
20:37:23 7632 8192000000 10206285328 10622468096 1314211936 13.824 100 13 26 0 1323574368 5518496 3843936
20:38:38 20836 8192000000 10238883280 10650152960 1338011408 14.039 100 40 80 1 1353490448 6156704 9322336
20:40:03 82086 8192000000 10260361024 10682572800 1358213232 14.221 100 39 78 0 1376107232 7928256 9965744
20:42:30 17382 8192000000 10281010912 10701946880 1373573824 14.359 100 52 103 2 1398005920 12589632 11842464
20:43:52 8855 8192000000 10301768368 10723831808 1386327664 14.473 100 55 110 0 1412382736 12909360 13145712
20:45:07 17380 8192000000 10309323504 10728935424 1386530784 14.475 100 51 102 1 1415761776 17053856 12177136
20:46:30 15688 8192000000 10324932048 10751856640 1404105328 14.632 100 59 118 2 1440071408 22197632 13768448
20:47:52 2046 8192000000 10330911568 10758561792 1409010608 14.675 100 61 120 0 1445752864 22054912 14687344
20:48:59 9053 8192000000 10334465392 10756280320 1402242944 14.615 100 59 118 2 1443291776 26994352 14054480
20:50:15 5086 8192000000 10362917520 10788061184 1418186176 14.757 100 61 122 2 1469581024 35909776 15485072
20:51:25 2189 8192000000 10365134912 10779475968 1414895200 14.727 100 83 164 1 1467101824 32458832 19747792
20:52:31 4126 8192000000 10327786160 10743857152 1375722768 14.378 99 74 148 1 1428088176 34148256 18217152
20:53:40 2858 8192000000 10343654560 10750164992 1385167376 14.463 100 81 162 0 1447032112 40975728 20889008
20:54:48 4504 8192000000 10340827296 10750177280 1390135088 14.507 100 70 140 1 1446115808 37844448 18136272
20:56:00 2636 8192000000 10358620768 10766794752 1397633536 14.574 100 92 182 1 1460399712 39974304 22791872
20:57:05 3715 8192000000 10341855392 10746875904 1368804320 14.316 99 84 166 3 1433661984 43276896 21580768
20:58:11 6108 8192000000 10320007216 10735087616 1365083728 14.283 99 48 94 1 1415038112 37329568 12624816
20:59:24 3402 8192000000 10344644432 10754035712 1373937760 14.362 99 74 148 1 1436403888 43206816 19259312
21:00:31 8624 8192000000 10359370448 10785366016 1396635840 14.565 100 50 100 0 1461759424 51251568 13872016
21:01:59 16885 8192000000 10347974032 10775810048 1386398656 14.474 99 37 74 0 1440370512 43278640 10693216
21:03:28 16817 8192000000 10329214672 10762567680 1378426752 14.402 100 37 74 0 1437589392 48145984 11016656
21:05:04 22924 8192000000 10316379280 10759409664 1365605024 14.288 99 27 53 2 1419842672 46430688 7806960
21:06:35 25010 8192000000 10314624144 10760896512 1368160448 14.311 100 17 34 1 1411766512 38787120 4818944
21:08:41 58293 8192000000 10274431088 10719977472 1347721520 14.127 98 17 34 0 1363479616 11835584 3922512
21:10:46 71042 8192000000 10304538592 10746507264 1376396720 14.384 99 39 78 1 1396660688 11223952 9040016
21:13:10 114654 8192000000 10282618208 10734309376 1345573440 14.108 97 11 23 0 1361281376 13228464 2479472
21:16:07 40600 8192000000 10291757472 10749644800 1381952048 14.434 99 10 20 0 1397195328 13028848 2214432
21:18:55 71036 8192000000 10294709504 10763161600 1387831552 14.487 100 15 30 0 1408021024 17308944 2880528
21:21:06 118534 8192000000 10286750768 10737479680 1346115920 14.113 96 23 46 0 1365859536 14206480 5537136
21:24:07 44015 8192000000 10287628624 10744119296 1375707424 14.378 99 29 57 0 1389877104 8154032 6015648
21:26:14 20029 8192000000 10305752560 10761564160 1396328560 14.562 100 15 30 0 1416922416 16602256 3991600
21:27:35 43489 8192000000 10310695328 10760777728 1389009264 14.497 99 30 60 0 1410703536 14787664 6906608
21:29:25 57056 8192000000 10303026032 10753425408 1389929424 14.505 100 13 26 0 1405878816 12966848 2982544
21:31:28 93433 8192000000 10298751088 10753835008 1381255200 14.428 99 14 27 0 1392339744 7921776 3162768
21:34:02 16909 8192000000 10294335776 10756829184 1397868544 14.576 100 18 35 3 1409685248 7888960 3927744
21:35:30 75694 8192000000 10299377072 10769948672 1385276720 14.464 99 11 22 0 1401506048 13021184 3208144
21:37:49 96814 8192000000 10316001408 10780106752 1401230240 14.606 100 13 26 1 1421955872 17280512 3445120
21:40:32 37095 8192000000 10306448512 10750275584 1364199520 14.275 98 28 54 3 1381521664 11826592 5495552
21:42:10 38956 8192000000 10306474960 10749587456 1343253760 14.087 96 38 77 2 1370721632 18225584 9242288
21:43:49 42577 8192000000 10308085904 10752012288 1367243856 14.302 98 23 46 0 1385747408 13234832 5268720
21:45:32 34747 8192000000 10294498320 10730016768 1328330464 13.952 96 32 64 0 1346709632 11443728 6935440
21:47:07 46659 8192000000 10277071184 10726940672 1341486912 14.071 98 21 42 0 1357621744 11596912 4537920
21:49:12 47620 8192000000 10298545392 10744078336 1368032880 14.309 100 25 50 1 1382997232 9489200 5475152
21:51:24 137657 8192000000 10263230064 10712981504 1330621328 13.973 97 21 42 0 1342339216 7283152 4434736
21:54:47 24512 8192000000 10271336880 10723164160 1369066224 14.319 100 41 82 1 1389606784 11218800 9321760
21:56:13 96065 8192000000 10254620000 10716151808 1318922384 13.867 96 10 20 1 1330946624 9778464 2245776
21:58:57 108208 8192000000 10269175632 10719608832 1330351696 13.970 97 14 28 0 1343281104 9755120 3174288
22:01:48 72347 8192000000 10274851808 10732576768 1348651088 14.135 98 13 27 0 1367204672 15504720 3048864
22:04:03 49323 8192000000 10289006816 10736656384 1351968304 14.165 98 44 88 1 1370426112 7634064 10823744
22:05:52 112363 8192000000 10277160912 10735980544 1354415200 14.187 99 12 24 0 1369812464 12664592 2732672
22:08:47 28819 8192000000 10282633776 10726621184 1348826320 14.137 98 43 84 1 1369809152 11822544 9160288
22:10:20 45053 8192000000 10302875024 10746638336 1370838048 14.335 99 34 68 0 1387728560 9963888 6926624
22:12:07 65032 8192000000 10279037568 10723516416 1304856576 13.739 94 16 32 0 1318030384 10002144 3171664
22:14:19 94890 8192000000 10291832336 10756259840 1351971056 14.165 98 13 26 0 1364720768 10033936 2715776
22:16:54 62752 8192000000 10278705920 10730889216 1366926832 14.300 99 29 58 0 1385911120 12464192 6520096
22:18:59 78910 8192000000 10284498368 10749616128 1386358944 14.473 100 26 51 2 1404178096 11797872 6021280
22:21:24 50509 8192000000 10285186000 10746691584 1359232128 14.230 98 10 20 0 1369929408 7703088 2994192
22:23:15 91781 8192000000 10288979728 10754359296 1390424656 14.510 100 14 27 2 1408162112 14409808 3327648
22:26:50 52718 8192000000 10284366752 10746425344 1337220048 14.032 96 21 42 0 1355131152 13231168 4679936
22:28:55 44590 8192000000 10279696528 10728251392 1353925248 14.183 98 16 32 0 1371801504 14210080 3666176
22:30:40 33690 8192000000 10268803104 10722758656 1355231552 14.195 99 18 36 0 1370628288 11571392 3825344
22:32:17 81972 8192000000 10288755296 10728767488 1337306512 14.033 97 30 60 4 1354052864 9969648 6776704
22:34:39 29724 8192000000 10301647488 10744168448 1380991168 14.425 100 48 96 0 1400613952 9134704 10488080
22:36:11 82261 8192000000 10304279216 10745925632 1346775072 14.118 97 32 63 0 1363901248 10376064 6750112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-01-2022 22:39:03 to 13-01-2022 22:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-01-2022 22:39:19 to 13-01-2022 22:39:21.

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          239075
MON$OLDEST_ACTIVE               240905
MON$OLDEST_SNAPSHOT             240905
MON$NEXT_TRANSACTION            240905
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                       2018048
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.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:896: 
:897: mscs-test-161.budzdorov.ru    Thu Jan 13 22:38:11 2022
:898:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:899: 
:900: 
:901: mscs-test-161.budzdorov.ru    Thu Jan 13 22:38:15 2022
:902:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:903: 
:904: 
:905: mscs-test-161.budzdorov.ru    Thu Jan 13 22:38:17 2022
:906:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:907: 
:908: 
:909: mscs-test-161.budzdorov.ru    Thu Jan 13 22:38:18 2022
:910:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:911: 
--- end of diff output ---

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

13.01.22 22:39:22. 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-01-13 22:39:24 No severe PSQL-related problems occured
13.01.22 22:39:25. End of report.