Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.07.2022 01: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 16268100 kB
MemAvailable 31075236 kB
Buffers 0 kB
Cached 15434384 kB
SwapCached 2532 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-15 01:38:21
DTS_END                         2022-07-15 01:38:21.0610
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/33960
TRN_ID                          244781
ATT_ID                          654
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: 9186.45 22063 1102374
customer order: creation 174.01 362 20881
customer order: refuse 14.82 347 1778
order to supplier: creation 47.49 1135 5699
order to supplier: removal 3.37 108877 404
invoice (draft): creation 46.63 3293 5596
invoice (draft): removal 52.03 826 6243
invoice accept: apply 41.65 17295 4998
invoice accept: cancel 47.12 764 5654
customer reserve: creation 3848.25 169 461790
customer reserve: removal 4615.16 62 553819
realization accept: apply 85.25 202 10230
realization accept: cancel 78.93 112 9472
payment from customer: creation 60.83 167 7299
payment from customer: removal 5.68 81 682
payment to supplier: creation 48.64 161 5837
payment to supplier: removal 3.69 106 443
service: total inventory turnovers 5.15 1016 618
service: total monetary turnovers 6.86 43 823
service: aggregate perf. data 0.87 19478 104
service: refresh index statistics 0.03 286761 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-07-2022 01:39:35 to 15-07-2022 01:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5102
TEST_TIME 2 3509
TEST_TIME 3 3576
TEST_TIME 4 3193
TEST_TIME 5 3353
TEST_TIME 6 3326
TEST_TIME 7 3567
TEST_TIME 8 3924
TEST_TIME 9 4098
TEST_TIME 10 4521
TEST_TIME 11 5377
TEST_TIME 12 5584
TEST_TIME 13 5798
TEST_TIME 14 6186
TEST_TIME 15 6436
TEST_TIME 16 6572
TEST_TIME 17 6717
TEST_TIME 18 6947
TEST_TIME 19 7368
TEST_TIME 20 7714
TEST_TIME 21 8194
TEST_TIME 22 8642
TEST_TIME 23 8947
TEST_TIME 24 9032
TEST_TIME 25 9441
TEST_TIME 26 10273
TEST_TIME 27 10869
TEST_TIME 28 10726
TEST_TIME 29 10626
TEST_TIME 30 10453
TEST_TIME 31 10567
TEST_TIME 32 10297
TEST_TIME 33 10183
TEST_TIME 34 10141
TEST_TIME 35 10254
TEST_TIME 36 10121
TEST_TIME 37 9973
TEST_TIME 38 9984
TEST_TIME 39 9910
TEST_TIME 40 9804
TEST_TIME 41 9788
TEST_TIME 42 9913
TEST_TIME 43 9766
TEST_TIME 44 9695
TEST_TIME 45 9581
TEST_TIME 46 9466
TEST_TIME 47 9633
TEST_TIME 48 9639
TEST_TIME 49 9474
TEST_TIME 50 9426
TEST_TIME 51 9484
TEST_TIME 52 9481
TEST_TIME 53 9435
TEST_TIME 54 9573
TEST_TIME 55 9529
TEST_TIME 56 9541
TEST_TIME 57 9600
TEST_TIME 58 9526
TEST_TIME 59 9573
TEST_TIME 60 9587
TEST_TIME 61 9692
TEST_TIME 62 9655
TEST_TIME 63 9581
TEST_TIME 64 9744
TEST_TIME 65 9701
TEST_TIME 66 9606
TEST_TIME 67 9536
TEST_TIME 68 9464
TEST_TIME 69 9455
TEST_TIME 70 9441
TEST_TIME 71 9417
TEST_TIME 72 9379
TEST_TIME 73 9278
TEST_TIME 74 9223
TEST_TIME 75 9245
TEST_TIME 76 9221
TEST_TIME 77 9249
TEST_TIME 78 9318
TEST_TIME 79 9279
TEST_TIME 80 9346
TEST_TIME 81 9358
TEST_TIME 82 9355
TEST_TIME 83 9413
TEST_TIME 84 9383
TEST_TIME 85 9458
TEST_TIME 86 9466
TEST_TIME 87 9493
TEST_TIME 88 9473
TEST_TIME 89 9386
TEST_TIME 90 9349
TEST_TIME 91 9342
TEST_TIME 92 9278
TEST_TIME 93 9252
TEST_TIME 94 9252
TEST_TIME 95 9224
TEST_TIME 96 9196
TEST_TIME 97 9223
TEST_TIME 98 9202
TEST_TIME 99 9301
TEST_TIME 100 9331
TEST_TIME 101 9338
TEST_TIME 102 9426
TEST_TIME 103 9434
TEST_TIME 104 9464
TEST_TIME 105 9505
TEST_TIME 106 9486
TEST_TIME 107 9451
TEST_TIME 108 9463
TEST_TIME 109 9387
TEST_TIME 110 9353
TEST_TIME 111 9346
TEST_TIME 112 9317
TEST_TIME 113 9300
TEST_TIME 114 9293
TEST_TIME 115 9275
TEST_TIME 116 9255
TEST_TIME 117 9236
TEST_TIME 118 9248
TEST_TIME 119 9252
TEST_TIME 120 9190

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-07-2022 01:39:38 to 15-07-2022 01:39:42.

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 1530071 1530071 0 0.00 0 36667 6 0 0
doc_list_biud 1530071 1530071 0 0.00 0 34617 29 0 0
sp_add_invoice_to_stock 5121 4998 123 2.40 23 188527 17295 0 123
sp_cancel_adding_invoice 5654 5654 0 0.00 4 70777 765 0 0
sp_cancel_client_order 1778 1778 0 0.00 4 9520 348 0 0
sp_cancel_customer_reserve 553819 553819 0 0.00 0 30309 63 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 2 8751 81 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 13597 106 0 0
sp_cancel_supplier_invoice 6253 6243 10 0.15 1 34622 827 0 10
sp_cancel_supplier_order 404 404 0 0.00 8 993375 108877 0 0
sp_cancel_write_off 9472 9472 0 0.00 1 17142 112 0 0
sp_client_order 20881 20881 0 0.00 3 27163 363 0 0
sp_customer_reserve 461790 461790 0 0.00 0 152551 170 0 0
sp_fill_shopping_cart 32174 32174 0 0.00 1 23789 42 0 0
sp_fill_shopping_cart_clo_res 461782 461782 0 0.00 0 22314 16 0 0
sp_get_clo_for_invoice 4989 4989 0 0.00 0 26861 330 0 0
sp_kill_cost_storno 16251 16251 0 0.00 0 16477 56 0 0
sp_kill_qstorno_ret_qs2qd 1123757 1123757 0 0.00 0 34615 34 0 0
sp_kill_qty_storno 592589 592589 0 0.00 0 34616 73 0 0
sp_lock_selected_doc 21125 21125 0 0.00 0 10410 5 0 0
sp_make_cost_storno 28355 28355 0 0.00 0 36586 57 0 0
sp_multiply_rows_for_pdistr 28355 28355 0 0.00 0 36506 35 0 0
sp_multiply_rows_for_qdistr 20881 20881 0 0.00 0 23020 169 0 0
sp_pay_from_customer 7299 7299 0 0.00 4 38624 168 0 0
sp_pay_to_supplier 5837 5837 0 0.00 5 37601 162 0 0
sp_payment_common 13136 13136 0 0.00 1 36490 75 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 9509 320 0 0
sp_qd_handle_on_invoice_upd_sts 10643 10643 0 0.00 0 22898 336 0 0
sp_qd_handle_on_reserve_upd_sts 10230 10230 0 0.00 0 15917 61 0 0
sp_reserve_write_off 10230 10230 0 0.00 6 36685 203 0 0
sp_supplier_invoice 5596 5596 0 0.00 5 88526 3293 0 0
sp_supplier_order 5699 5699 0 0.00 5 57553 1135 0 0
srv_aggregate_perf_data 104 104 0 0.00 2756 252272 19479 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1081 143965 47509 0 0
srv_find_qd_qs_mism 1054202 1054202 0 0.00 0 15106 5 0 0
srv_make_invnt_saldo 618 618 0 0.00 34 89575 1016 0 0
srv_make_money_saldo 823 823 0 0.00 0 12859 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 188215 402946 286762 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4998 4998 0 0.00 0 8827 152 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 9023 101 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 4400 18 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 2375 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 3813 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 1268 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 3856 24 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 25678 1782 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 2628 8 0 0
t$perf-norm:sp_client_order 20881 20881 0 0.00 0 7419 15 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 1031 5 0 0
t$perf-norm:sp_pay_from_customer 7299 7299 0 0.00 0 9302 10 0 0
t$perf-norm:sp_pay_to_supplier 5837 5837 0 0.00 0 4099 9 0 0
t$perf-norm:sp_reserve_write_off 10230 10230 0 0.00 0 6968 11 0 0
t$perf-norm:sp_supplier_invoice 5596 5596 0 0.00 0 5841 19 0 0
t$perf-norm:sp_supplier_order 5699 5699 0 0.00 0 8980 14 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 460 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 5500 96 0 0
t$perf-norm:srv_make_invnt_saldo 618 618 0 0.00 0 692 22 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 6343 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10224 9978 246 2.40 0 5149 16 0 246
v_all_customers 38248 38248 0 0.00 0 127 1 0 0
v_all_suppliers 30962 30962 0 0.00 0 117 1 0 0
v_all_wares 41762 41762 0 0.00 0 95 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 4763 16 0 0
v_cancel_client_order 3556 3556 0 0.00 0 4716 6 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 3084 4 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 874 4 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 1064 5 0 0
v_cancel_supplier_invoice 896 876 20 2.23 0 2276 15 0 20
v_cancel_supplier_order 808 808 0 0.00 0 1029 7 0 0
v_cancel_write_off 2082 2082 0 0.00 0 4819 19 0 0
v_max_id_clo_ord 5699 5699 0 0.00 0 531 1 0 0
v_max_id_clo_res 951 951 0 0.00 0 2345 13 0 0
v_max_id_ord_sup 5594 5594 0 0.00 0 5163 8 0 0
v_max_non_paid_invoice 1649 1649 0 0.00 0 231 2 0 0
v_max_non_paid_realizn 3819 3819 0 0.00 0 5154 6 0 0
v_min_id_clo_ord 5699 5699 0 0.00 0 23781 12 0 0
v_min_id_clo_res 951 951 0 0.00 0 600 4 0 0
v_min_id_ord_sup 5594 5594 0 0.00 0 5139 7 0 0
v_min_non_paid_invoice 5837 5837 0 0.00 0 35195 65 0 0
v_min_non_paid_realizn 7299 7299 0 0.00 0 36480 66 0 0
v_our_firm 10474 10474 0 0.00 0 28 1 0 0
v_reserve_write_off 20458 20458 0 0.00 0 5406 9 0 0
x_lock_depdocs_on_canc_invoice 11459 11459 0 0.00 0 17547 117 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 13402 420 0 0
x_make_qstorno_1000_1200 5699 5699 0 0.00 1 57546 1060 0 0
x_make_qstorno_1200_2000 5596 5596 0 0.00 2 88498 3127 0 0
x_make_qstorno_2100_3300 461789 461789 0 0.00 0 152549 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-07-2022 01:39:43 to 15-07-2022 01:39:47.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:24 6 8192000000 8808466496 8822886400 13424848 0.163 4 4 3 0 13572240 72448 74944
23:09:26 17149 8192000000 9903674784 10214699008 986583424 10.748 100 84 167 0 1013501248 5655472 21262352
23:10:51 19229 8192000000 9964251744 10288984064 1040983072 11.274 100 90 180 0 1072427296 8561008 22883216
23:12:14 17780 8192000000 9986329232 10321096704 1070375152 11.556 100 88 176 0 1102767328 10611504 21780672
23:13:34 13172 8192000000 10021670208 10364579840 1109735424 11.930 100 81 161 0 1140217152 11249888 19231840
23:14:51 5713 8192000000 10070721680 10427363328 1158910912 12.393 100 74 146 0 1187771872 12040064 16820896
23:16:02 3722 8192000000 10109295424 10474467328 1199539680 12.772 100 84 168 0 1232363424 14203216 18620528
23:17:08 1497 8192000000 10143253744 10518315008 1234999872 13.100 100 83 160 0 1265782400 12907008 17875520
23:18:11 1996 8192000000 10181193440 10558935040 1269542624 13.417 100 87 172 1 1304267456 15223152 19501680
23:19:14 1163 8192000000 10205122624 10593509376 1295394032 13.653 100 79 156 0 1326072032 13554512 17123488
23:20:16 26318 8192000000 10206262304 10605264896 1305053728 13.741 100 60 120 0 1326994368 6668512 15272128
23:21:44 7177 8192000000 10203553504 10612469760 1311751584 13.802 100 42 83 0 1328648272 8236592 8660096
23:22:53 7944 8192000000 10200618256 10618740736 1317287152 13.852 100 18 35 0 1327516256 6630144 3598960
23:24:04 5421 8192000000 10212785232 10628067328 1324311616 13.916 100 25 49 0 1336180016 6707504 5160896
23:25:09 55930 8192000000 10239204976 10643369984 1329513040 13.963 100 47 94 0 1346757568 6398912 10845616
23:27:06 5257 8192000000 10219945072 10641342464 1329185888 13.960 100 30 59 0 1347242416 11756752 6299776
23:28:12 6180 8192000000 10208006688 10626789376 1301378080 13.708 98 23 46 0 1312590896 5686704 5526112
23:29:22 59556 8192000000 10193509696 10591424512 1259121328 13.322 97 38 76 1 1273049200 4528480 9399392
23:31:22 9393 8192000000 10126048256 10526040064 1179039680 12.581 94 15 29 0 1187502736 5208848 3254208
23:32:32 6311 8192000000 10147540608 10537168896 1242077824 13.165 99 30 59 0 1254670912 5934944 6658144
23:33:42 8867 8192000000 10159028832 10571878400 1262458544 13.353 99 22 44 0 1277722784 10104752 5159488
23:34:51 23479 8192000000 10176907984 10590887936 1286263136 13.570 100 25 50 0 1297837840 6033088 5541616
23:36:16 5926 8192000000 10201994352 10613108736 1304117216 13.733 100 36 70 0 1318530992 6630016 7783760
23:37:25 11744 8192000000 10199998672 10619953152 1312959136 13.813 100 15 31 0 1322196400 5408416 3828848
23:38:41 31187 8192000000 10246906032 10659586048 1342863040 14.083 100 40 78 0 1359608320 7524256 9221024
23:40:17 75683 8192000000 10271136912 10691092480 1365379024 14.286 100 45 90 1 1386729744 10619232 10731488
23:42:47 31411 8192000000 10293812368 10717425664 1386688304 14.476 100 42 83 1 1413173168 16898560 9586304
23:44:22 16853 8192000000 10308194000 10735632384 1398298608 14.580 100 34 64 0 1426504160 19426064 8779488
23:45:47 9431 8192000000 10330073584 10756046848 1412645696 14.707 100 46 91 0 1449722016 25684832 11391488
23:47:05 15005 8192000000 10352585344 10778890240 1421498800 14.786 100 52 104 1 1463182464 28377520 13306144
23:48:25 4472 8192000000 10374652496 10801885184 1434319872 14.899 100 52 105 1 1485315232 37090688 13904672
23:49:36 2524 8192000000 10371355296 10793050112 1428056368 14.844 100 60 120 1 1479217856 36240816 14920672
23:50:45 8118 8192000000 10370368592 10796785664 1424068704 14.809 100 60 120 0 1481923696 43128896 14726096
23:52:02 1800 8192000000 10381343568 10807132160 1428661888 14.849 100 67 135 1 1488356416 42375904 17318624
23:53:08 9125 8192000000 10367652416 10782085120 1415466080 14.732 99 83 164 1 1473698128 38828400 19403648
23:54:20 2533 8192000000 10378059392 10794229760 1417543216 14.751 100 74 148 0 1483376592 47042000 18791376
23:55:31 2425 8192000000 10379343968 10794582016 1410843856 14.691 100 78 155 1 1485628272 54928576 19855840
23:56:39 2190 8192000000 10392132560 10800218112 1418072832 14.756 100 81 153 1 1492760240 55586288 19101120
23:57:50 1071 8192000000 10369649312 10778386432 1393116656 14.534 99 82 162 3 1470649120 57230608 20301856
23:58:56 5164 8192000000 10358193616 10787815424 1399415456 14.590 100 50 100 0 1473503824 60593776 13494592
00:00:05 12935 8192000000 10343587552 10771685376 1388940096 14.496 100 43 86 0 1453710320 53037376 11732848
00:01:20 11063 8192000000 10323996720 10759737344 1367683456 14.306 99 30 61 0 1423721280 47525296 8512528
00:02:34 15428 8192000000 10313424640 10744434688 1356122000 14.203 99 32 64 0 1412315088 47306640 8886448
00:03:52 25199 8192000000 10275598688 10714038272 1296195072 13.661 96 17 34 0 1346536288 45313296 5027920
00:05:21 2881 8192000000 10265092528 10687307776 1344918128 14.102 100 55 111 2 1367084144 9673328 12492688
00:06:26 12240 8192000000 10257251808 10702626816 1351326656 14.159 100 14 28 0 1369784944 15208528 3249760
00:07:48 85411 8192000000 10261396496 10710405120 1333918032 14.003 98 19 37 1 1356398448 18488368 3992048
00:10:14 61006 8192000000 10244759520 10674249728 1279655536 13.510 94 39 72 0 1293298912 5515632 8127744
00:12:45 69068 8192000000 10259402992 10699186176 1326267328 13.933 98 19 33 1 1342908272 12964944 3676000
00:14:58 101498 8192000000 10264022160 10711158784 1327994752 13.949 97 16 32 0 1342598000 10786592 3816656
00:17:40 20648 8192000000 10271723504 10714103808 1358922176 14.228 99 22 44 0 1379243520 15258864 5062480
00:19:03 40729 8192000000 10275336752 10726899712 1367680784 14.306 100 36 72 0 1395226288 19031648 8513856
00:20:54 143943 8192000000 10286722144 10744561664 1364832944 14.281 98 13 26 0 1384558288 16391616 3333728
00:24:19 75811 8192000000 10280150864 10746036224 1377948320 14.398 100 20 40 0 1402886640 20037232 4901088
00:26:50 48327 8192000000 10265498064 10727276544 1372829520 14.352 99 4 8 0 1382178448 8470560 878368
00:28:40 93004 8192000000 10285546352 10752090112 1391446000 14.519 100 17 34 0 1409260608 13933536 3881072
00:31:19 32476 8192000000 10316630544 10768498688 1399562816 14.591 100 22 44 0 1415652544 11075472 5014256
00:32:51 68779 8192000000 10282127344 10732544000 1348654416 14.135 97 18 37 0 1363112784 10451632 4006736
00:35:10 41845 8192000000 10288105664 10740547584 1355420384 14.196 98 13 24 0 1365123904 7116512 2587008
00:36:52 33944 8192000000 10281854736 10728108032 1338026784 14.040 97 27 54 0 1357208496 12974016 6207696
00:38:26 38410 8192000000 10276148096 10720223232 1330108496 13.968 96 16 33 0 1344557920 10921008 3528416
00:40:05 67276 8192000000 10280677472 10719768576 1283919408 13.549 93 16 30 0 1295186976 8402304 2865264
00:42:13 137054 8192000000 10280829408 10727182336 1315242144 13.834 95 15 30 0 1337082368 18524608 3315616
00:45:49 51874 8192000000 10267608448 10715148288 1345182320 14.104 98 23 44 0 1358053584 8068064 4803200
00:47:43 56506 8192000000 10267129072 10724106240 1364440368 14.277 99 16 32 0 1375873296 8049600 3383328
00:50:00 88292 8192000000 10251870112 10706694144 1298771504 13.684 95 9 18 0 1309637472 8882064 1983904
00:52:55 65972 8192000000 10243365632 10705395712 1277257408 13.488 94 9 18 0 1292725248 13649408 1818432
00:55:01 75239 8192000000 10250479824 10714165248 1352769408 14.172 100 16 32 0 1370860848 14272800 3818640
00:57:32 36298 8192000000 10269647168 10708430848 1325173440 13.924 97 21 42 0 1342092480 12372944 4546096
00:59:11 37091 8192000000 10267840864 10712535040 1341215328 14.068 98 32 64 1 1360946112 12702000 7028784
01:00:55 85086 8192000000 10287878368 10733252608 1362830480 14.263 100 17 34 0 1379656336 12731984 4093872
01:03:27 75029 8192000000 10279714064 10725892096 1343703504 14.091 98 19 37 2 1362950528 14681856 4565168
01:05:49 60069 8192000000 10289335968 10724093952 1356725296 14.208 99 29 58 0 1372670624 9106032 6839296
01:07:49 110096 8192000000 10258030576 10719330304 1346353920 14.115 98 28 56 1 1362425232 9832016 6239296
01:10:40 49178 8192000000 10252462320 10714701824 1327735024 13.947 96 10 20 1 1336569456 6904496 1929936
01:12:33 67384 8192000000 10276129840 10728534016 1325496528 13.926 96 13 26 0 1339068656 11017984 2554144
01:15:14 65481 8192000000 10310892288 10765717504 1396930544 14.568 100 12 23 0 1420142752 20479152 2733056
01:17:20 135397 8192000000 10314918800 10783842304 1371540800 14.341 98 11 23 0 1384573408 10427440 2605168
01:20:51 72973 8192000000 10300467360 10762461184 1349508544 14.143 97 7 14 0 1372194096 21394128 1291424
01:23:10 37773 8192000000 10285777680 10729861120 1345331456 14.105 98 29 58 0 1362227584 10252736 6643392
01:24:50 17617 8192000000 10291361600 10735583232 1375669216 14.378 100 27 55 0 1390058976 8376816 6012944
01:26:08 9290 8192000000 10302148912 10732142592 1367718640 14.307 99 51 100 0 1390224880 12024064 10482176
01:27:21 91406 8192000000 10259003072 10707419136 1333480656 13.999 97 33 64 0 1350922464 9482032 7959776
01:29:55 69997 8192000000 10249948432 10706395136 1340501568 14.062 99 9 18 0 1353086608 10518928 2066112
01:32:05 71393 8192000000 10259152752 10715242496 1340484400 14.062 98 13 27 0 1351598256 8187136 2926720
01:34:50 42443 8192000000 10239271376 10699632640 1325556720 13.927 98 13 26 1 1339418560 11314368 2547472
01:36:39 63105 8192000000 10254809328 10703695872 1310629744 13.792 97 13 26 0 1323443296 9937552 2876000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 15-07-2022 01:39:49 to 15-07-2022 01:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-07-2022 01:40:04 to 15-07-2022 01:40:06.

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          243976
MON$OLDEST_ACTIVE               244909
MON$OLDEST_SNAPSHOT             244909
MON$NEXT_TRANSACTION            244909
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                       2010240
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 ---
:1105: 
:1106: mscs-test-161.budzdorov.ru    Fri Jul 15 01:38:28 2022
:1107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1108: 
:1109: 
:1110: mscs-test-161.budzdorov.ru    Fri Jul 15 01:38:28 2022
:1111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1112: 
--- end of diff output ---

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

15.07.22 01:40:07. 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-15 01:40:10 No severe PSQL-related problems occured
15.07.22 01:40:11. End of report.