Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.04.2022 19: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 16807880 kB
MemAvailable 31615476 kB
Buffers 0 kB
Cached 14935884 kB
SwapCached 2204 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-04-01 19:38:15
DTS_END                         2022-04-01 19:38:15.7860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38456
TRN_ID                          243205
ATT_ID                          667
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: 9209.00 15518 1105080
customer order: creation 184.38 342 22125
customer order: refuse 15.88 355 1906
order to supplier: creation 48.24 1337 5789
order to supplier: removal 3.36 102275 403
invoice (draft): creation 48.85 3241 5862
invoice (draft): removal 49.93 837 5991
invoice accept: apply 44.43 17875 5331
invoice accept: cancel 44.66 881 5359
customer reserve: creation 4051.32 179 486158
customer reserve: removal 4422.35 61 530682
realization accept: apply 87.60 174 10512
realization accept: cancel 72.24 107 8669
payment from customer: creation 62.40 91 7488
payment from customer: removal 5.72 85 686
payment to supplier: creation 51.10 115 6132
payment to supplier: removal 3.46 80 415
service: total inventory turnovers 5.19 1068 623
service: total monetary turnovers 7.00 24 840
service: aggregate perf. data 0.88 16636 105
service: refresh index statistics 0.03 164601 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-04-2022 19:39:41 to 01-04-2022 19:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8441
TEST_TIME 2 6608
TEST_TIME 3 5326
TEST_TIME 4 5770
TEST_TIME 5 5567
TEST_TIME 6 5431
TEST_TIME 7 5213
TEST_TIME 8 5906
TEST_TIME 9 6098
TEST_TIME 10 6634
TEST_TIME 11 7254
TEST_TIME 12 7202
TEST_TIME 13 7357
TEST_TIME 14 7487
TEST_TIME 15 7677
TEST_TIME 16 7818
TEST_TIME 17 7833
TEST_TIME 18 8292
TEST_TIME 19 9043
TEST_TIME 20 9002
TEST_TIME 21 9080
TEST_TIME 22 9121
TEST_TIME 23 9947
TEST_TIME 24 10100
TEST_TIME 25 10307
TEST_TIME 26 11089
TEST_TIME 27 11076
TEST_TIME 28 11132
TEST_TIME 29 10926
TEST_TIME 30 10770
TEST_TIME 31 10671
TEST_TIME 32 10409
TEST_TIME 33 10578
TEST_TIME 34 10524
TEST_TIME 35 10279
TEST_TIME 36 10174
TEST_TIME 37 10126
TEST_TIME 38 10136
TEST_TIME 39 10068
TEST_TIME 40 10319
TEST_TIME 41 10217
TEST_TIME 42 10263
TEST_TIME 43 10173
TEST_TIME 44 10042
TEST_TIME 45 9929
TEST_TIME 46 9905
TEST_TIME 47 9782
TEST_TIME 48 9607
TEST_TIME 49 9596
TEST_TIME 50 9565
TEST_TIME 51 9551
TEST_TIME 52 9582
TEST_TIME 53 9564
TEST_TIME 54 9651
TEST_TIME 55 9653
TEST_TIME 56 9720
TEST_TIME 57 9777
TEST_TIME 58 9763
TEST_TIME 59 9832
TEST_TIME 60 9810
TEST_TIME 61 9832
TEST_TIME 62 9819
TEST_TIME 63 9800
TEST_TIME 64 9731
TEST_TIME 65 9663
TEST_TIME 66 9615
TEST_TIME 67 9606
TEST_TIME 68 9540
TEST_TIME 69 9465
TEST_TIME 70 9429
TEST_TIME 71 9449
TEST_TIME 72 9438
TEST_TIME 73 9391
TEST_TIME 74 9371
TEST_TIME 75 9473
TEST_TIME 76 9495
TEST_TIME 77 9521
TEST_TIME 78 9566
TEST_TIME 79 9515
TEST_TIME 80 9546
TEST_TIME 81 9530
TEST_TIME 82 9615
TEST_TIME 83 9641
TEST_TIME 84 9614
TEST_TIME 85 9558
TEST_TIME 86 9518
TEST_TIME 87 9487
TEST_TIME 88 9483
TEST_TIME 89 9410
TEST_TIME 90 9428
TEST_TIME 91 9408
TEST_TIME 92 9407
TEST_TIME 93 9387
TEST_TIME 94 9401
TEST_TIME 95 9450
TEST_TIME 96 9449
TEST_TIME 97 9463
TEST_TIME 98 9467
TEST_TIME 99 9542
TEST_TIME 100 9508
TEST_TIME 101 9521
TEST_TIME 102 9531
TEST_TIME 103 9502
TEST_TIME 104 9481
TEST_TIME 105 9521
TEST_TIME 106 9567
TEST_TIME 107 9517
TEST_TIME 108 9464
TEST_TIME 109 9449
TEST_TIME 110 9477
TEST_TIME 111 9426
TEST_TIME 112 9391
TEST_TIME 113 9367
TEST_TIME 114 9345
TEST_TIME 115 9288
TEST_TIME 116 9282
TEST_TIME 117 9271
TEST_TIME 118 9266
TEST_TIME 119 9283
TEST_TIME 120 9217

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-04-2022 19:39:45 to 01-04-2022 19:39:48.

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 1555180 1555180 0 0.00 0 14290 6 0 0
doc_list_biud 1555177 1555177 0 0.00 0 29331 27 0 0
sp_add_invoice_to_stock 5419 5331 88 1.62 29 172720 17876 0 88
sp_cancel_adding_invoice 5359 5359 0 0.00 4 65688 882 0 0
sp_cancel_client_order 1906 1906 0 0.00 4 12048 355 0 0
sp_cancel_customer_reserve 530682 530682 0 0.00 1 27371 62 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 2 8394 85 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 6990 80 0 0
sp_cancel_supplier_invoice 5995 5991 4 0.06 1 23496 838 0 4
sp_cancel_supplier_order 403 403 0 0.00 7 1093923 102275 0 0
sp_cancel_write_off 8669 8669 0 0.00 1 10552 107 0 0
sp_client_order 22125 22125 0 0.00 3 45091 343 0 0
sp_customer_reserve 486158 486158 0 0.00 0 140702 180 0 0
sp_fill_shopping_cart 33773 33773 0 0.00 1 20371 35 0 0
sp_fill_shopping_cart_clo_res 486140 486140 0 0.00 0 16323 13 0 0
sp_get_clo_for_invoice 5313 5313 0 0.00 0 27663 270 0 0
sp_kill_cost_storno 15129 15129 0 0.00 0 7765 57 0 0
sp_kill_qstorno_ret_qs2qd 1076427 1076427 0 0.00 0 27368 33 0 0
sp_kill_qty_storno 568834 568834 0 0.00 0 29327 73 0 0
sp_lock_selected_doc 21848 21848 0 0.00 0 6972 6 0 0
sp_make_cost_storno 29444 29444 0 0.00 0 12983 47 0 0
sp_multiply_rows_for_pdistr 29444 29444 0 0.00 0 6225 29 0 0
sp_multiply_rows_for_qdistr 22125 22125 0 0.00 0 45083 159 0 0
sp_pay_from_customer 7488 7488 0 0.00 4 9123 92 0 0
sp_pay_to_supplier 6132 6132 0 0.00 4 13560 115 0 0
sp_payment_common 13619 13619 0 0.00 1 10803 22 0 0
sp_qd_handle_on_cancel_clo 1906 1906 0 0.00 0 12033 335 0 0
sp_qd_handle_on_invoice_upd_sts 10671 10671 0 0.00 0 29322 347 0 0
sp_qd_handle_on_reserve_upd_sts 10512 10512 0 0.00 0 16383 54 0 0
sp_reserve_write_off 10512 10512 0 0.00 6 16465 175 0 0
sp_supplier_invoice 5862 5862 0 0.00 5 87717 3242 0 0
sp_supplier_order 5789 5789 0 0.00 5 27925 1338 0 0
srv_aggregate_perf_data 105 105 0 0.00 2691 221039 16637 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1111 188074 48660 0 0
srv_find_qd_qs_mism 1054651 1054651 0 0.00 0 14289 6 0 0
srv_make_invnt_saldo 623 623 0 0.00 26 40726 1068 0 0
srv_make_money_saldo 840 840 0 0.00 1 1821 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 69293 350314 164601 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5331 5331 0 0.00 0 27347 166 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 3708 90 0 0
t$perf-norm:sp_cancel_client_order 1906 1906 0 0.00 0 8916 18 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 1608 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 923 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 649 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 9311 32 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 25006 1631 0 0
t$perf-norm:sp_cancel_write_off 1079 1079 0 0.00 0 2555 12 0 0
t$perf-norm:sp_client_order 22125 22125 0 0.00 0 25240 18 0 0
t$perf-norm:sp_customer_reserve 1092 1092 0 0.00 0 2861 9 0 0
t$perf-norm:sp_pay_from_customer 7488 7488 0 0.00 0 16735 11 0 0
t$perf-norm:sp_pay_to_supplier 6132 6132 0 0.00 0 6727 8 0 0
t$perf-norm:sp_reserve_write_off 10512 10512 0 0.00 0 13847 10 0 0
t$perf-norm:sp_supplier_invoice 5862 5862 0 0.00 0 7181 18 0 0
t$perf-norm:sp_supplier_order 5789 5789 0 0.00 0 6152 15 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 442 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1884 33 0 0
t$perf-norm:srv_make_invnt_saldo 623 623 0 0.00 0 611 17 0 0
t$perf-norm:srv_make_money_saldo 840 840 0 0.00 0 769 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10800 10624 176 1.62 0 4933 17 0 176
v_all_customers 40338 40338 0 0.00 0 204 1 0 0
v_all_suppliers 31966 31966 0 0.00 0 121 1 0 0
v_all_wares 44246 44246 0 0.00 0 160 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 2723 11 0 0
v_cancel_client_order 3812 3812 0 0.00 0 1426 4 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 4620 11 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 873 4 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 1192 4 0 0
v_cancel_supplier_invoice 838 830 8 0.95 0 1219 13 0 8
v_cancel_supplier_order 806 806 0 0.00 0 1697 9 0 0
v_cancel_write_off 2158 2158 0 0.00 0 1245 11 0 0
v_max_id_clo_ord 5788 5788 0 0.00 0 706 1 0 0
v_max_id_clo_res 1092 1092 0 0.00 0 6167 21 0 0
v_max_id_ord_sup 5861 5861 0 0.00 0 4075 3 0 0
v_max_non_paid_invoice 1798 1798 0 0.00 0 2566 6 0 0
v_max_non_paid_realizn 3921 3921 0 0.00 0 1584 4 0 0
v_min_id_clo_ord 5788 5788 0 0.00 0 2860 8 0 0
v_min_id_clo_res 1092 1092 0 0.00 0 7299 10 0 0
v_min_id_ord_sup 5861 5861 0 0.00 0 3887 7 0 0
v_min_non_paid_invoice 6132 6132 0 0.00 0 10799 16 0 0
v_min_non_paid_realizn 7487 7487 0 0.00 0 7373 16 0 0
v_our_firm 11040 11040 0 0.00 0 17 1 0 0
v_reserve_write_off 21022 21022 0 0.00 0 7579 7 0 0
x_lock_depdocs_on_canc_invoice 10935 10935 0 0.00 0 16313 113 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 7383 363 0 0
x_make_qstorno_1000_1200 5789 5789 0 0.00 2 27903 1257 0 0
x_make_qstorno_1200_2000 5862 5862 0 0.00 2 87639 3120 0 0
x_make_qstorno_2100_3300 486158 486158 0 0.00 0 140699 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-04-2022 19:39:49 to 01-04-2022 19:39:53.

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
17:08:19 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:20 26435 8192000000 9894282352 10204864512 978351792 10.668 100 87 174 0 1007344288 6225744 22766752
17:10:52 13563 8192000000 9939532192 10260267008 1022932496 11.100 100 92 184 0 1055585264 8021696 24631072
17:12:13 22162 8192000000 9980560384 10311172096 1062318384 11.479 100 82 164 0 1092715248 9978352 20418512
17:13:39 10427 8192000000 10021323232 10369261568 1110218656 11.934 100 76 152 2 1139171936 10316192 18637088
17:14:54 8375 8192000000 10074216816 10434252800 1162432592 12.426 100 83 166 1 1194148400 13242816 18472992
17:16:06 6446 8192000000 10118990752 10488606720 1208238368 12.853 100 86 172 2 1241301968 13476832 19586768
17:17:15 4511 8192000000 10138258864 10519191552 1238490032 13.132 100 63 125 0 1263770480 11327520 13952928
17:18:21 3534 8192000000 10194593648 10575867904 1274360208 13.461 100 91 182 0 1308923712 14408224 20155280
17:19:27 1720 8192000000 10207865872 10589556736 1292592320 13.628 100 89 178 2 1325547136 12966064 19988752
17:20:33 4038 8192000000 10205032480 10604511232 1302898656 13.722 100 58 116 1 1327511152 11789184 12823312
17:21:38 28576 8192000000 10210303536 10607816704 1312830912 13.812 100 59 118 3 1333401328 6764864 13805552
17:23:08 9892 8192000000 10198186496 10614165504 1312072896 13.805 100 23 46 1 1323625856 6504784 5048176
17:24:19 11476 8192000000 10207979824 10627407872 1324372784 13.916 100 21 42 0 1335636480 6211088 5052608
17:25:32 31614 8192000000 10226890480 10647269376 1335842864 14.020 100 37 72 0 1350654864 5726080 9085920
17:27:24 4699 8192000000 10229634208 10647932928 1335904960 14.020 100 30 60 2 1349598112 7097328 6595824
17:28:29 6895 8192000000 10196920288 10604437504 1254931392 13.284 95 17 35 2 1263747104 4637200 4178512
17:29:37 11020 8192000000 10184525616 10597777408 1286800320 13.575 99 25 50 0 1298474560 5563088 6111152
17:30:52 71269 8192000000 10156085648 10563645440 1216790208 12.932 94 32 64 0 1230149632 5181312 8178112
17:33:03 8672 8192000000 10120948816 10522083328 1184524496 12.632 95 22 44 2 1195526464 5842656 5159312
17:34:13 7384 8192000000 10174634352 10576990208 1275833952 13.475 100 30 59 4 1289625232 6099632 7691648
17:35:21 8110 8192000000 10167819888 10584166400 1277020464 13.486 99 25 50 0 1289418800 5585936 6812400
17:36:31 5388 8192000000 10214234704 10603806720 1294365600 13.644 100 87 177 2 1322884816 6782752 21736464
17:37:36 8156 8192000000 10200319216 10614824960 1307846784 13.767 100 26 52 2 1321007376 6651824 6508768
17:38:50 12397 8192000000 10249226928 10655834112 1337487104 14.035 100 61 120 1 1359772880 8919024 13366752
17:40:09 66490 8192000000 10264511504 10686967808 1359372752 14.232 100 45 90 3 1381342576 10366000 11603824
17:42:27 27418 8192000000 10287832944 10713325568 1375086048 14.373 100 35 68 0 1397513440 14578192 7849200
17:44:05 12418 8192000000 10308516720 10729148416 1389219072 14.499 100 52 102 1 1418792192 17564576 12008544
17:45:30 11063 8192000000 10319818624 10739056640 1394880000 14.549 100 54 106 1 1427339632 19489760 12969872
17:46:45 3509 8192000000 10318314576 10745528320 1403590304 14.627 100 54 108 1 1434819840 18141696 13087840
17:47:52 3362 8192000000 10353210080 10766204928 1412286496 14.704 100 73 143 0 1455484048 25787952 17409600
17:49:03 5503 8192000000 10342558608 10763812864 1408054688 14.667 100 62 124 2 1453153424 29950896 15147840
17:50:12 4126 8192000000 10336490528 10765799424 1411817792 14.700 100 51 102 2 1450754704 26587104 12349808
17:51:23 5977 8192000000 10357535936 10775703552 1414543712 14.724 99 67 133 1 1463333968 32334016 16456240
17:52:34 2019 8192000000 10387975632 10804731904 1431970384 14.879 100 86 173 3 1490416816 37462496 20983936
17:53:42 4032 8192000000 10377956096 10788581376 1419896912 14.772 100 84 167 3 1479487632 39355856 20234864
17:54:56 2204 8192000000 10405836848 10823548928 1439695888 14.947 100 84 168 4 1502667888 41855360 21116640
17:56:02 2936 8192000000 10385370400 10795089920 1405906688 14.648 99 85 168 0 1474327264 46971648 21448928
17:57:10 4544 8192000000 10382518608 10808635392 1430528576 14.866 100 61 120 0 1489973408 43637872 15806960
17:58:37 18881 8192000000 10358541360 10798804992 1426239184 14.828 100 36 72 1 1472110512 36363776 9507552
18:00:01 6282 8192000000 10373390048 10800984064 1417394016 14.750 100 50 100 0 1474583328 44056720 13132592
18:01:13 9686 8192000000 10349230736 10777513984 1385422208 14.465 99 52 104 0 1450794880 51315536 14057136
18:02:29 35874 8192000000 10306891776 10751442944 1362711168 14.262 98 26 53 0 1403425664 33059808 7654688
18:04:08 19356 8192000000 10285302192 10742554624 1351134992 14.158 98 18 36 0 1385325536 29770800 4419744
18:05:29 28678 8192000000 10250505376 10695864320 1319841136 13.875 97 16 32 1 1334566224 10965904 3759184
18:07:06 35595 8192000000 10251549488 10686894080 1306552096 13.755 97 8 16 1 1315489728 7201488 1736144
18:08:42 117157 8192000000 10284517744 10718203904 1351839504 14.164 99 23 46 1 1366514720 9701360 4973856
18:11:55 188074 8192000000 10279123552 10714869760 1291771376 13.620 94 30 60 0 1309158144 10683248 6703520
18:16:08 43474 8192000000 10283791776 10730569728 1375617168 14.377 100 41 80 1 1404993600 19490368 9886064
18:18:08 51063 8192000000 10283152384 10746433536 1379042688 14.408 100 17 33 1 1400560816 17513648 4004480
18:20:58 73634 8192000000 10273231648 10739273728 1374960544 14.371 99 21 43 1 1389066400 9098640 5007216
18:23:16 1111 8192000000 10290629376 10731376640 1372896592 14.353 99 59 118 3 1391478272 5070976 13510704
18:24:17 38410 8192000000 10246655280 10701848576 1342878336 14.083 98 9 18 1 1352185392 6872416 2434640
18:26:00 88542 8192000000 10259436256 10723196928 1359206912 14.230 99 17 34 0 1369694448 6544016 3943520
18:28:31 26166 8192000000 10259524720 10717376512 1357214064 14.212 99 18 37 1 1368198368 7154528 3829776
18:29:58 95111 8192000000 10282507888 10748489728 1335286864 14.015 96 14 28 1 1359620224 20849088 3484272
18:32:36 18879 8192000000 10280305120 10726506496 1352133984 14.167 98 27 54 0 1368046816 9563088 6349744
18:34:00 42945 8192000000 10293225680 10743910400 1387012528 14.479 100 9 18 0 1402195552 13024720 2158304
18:35:50 58339 8192000000 10290454064 10748071936 1348831360 14.137 97 24 47 3 1364583760 10422688 5329712
18:37:48 70782 8192000000 10291130176 10737655808 1340337680 14.060 97 26 52 1 1358644608 11974944 6331984
18:39:59 36366 8192000000 10282975248 10733940736 1366352976 14.294 99 28 56 0 1384584960 12308784 5923200
18:41:41 111831 8192000000 10251890752 10701938688 1317488512 13.854 96 20 40 1 1331522640 9104720 4929408
18:44:36 35966 8192000000 10256553712 10710818816 1358182912 14.221 99 19 38 0 1370454016 7793744 4477360
18:46:22 34469 8192000000 10260867440 10717106176 1362153344 14.257 100 7 14 0 1373030048 9291136 1585568
18:47:57 66404 8192000000 10245506320 10701742080 1321737536 13.892 97 9 18 1 1336101776 12197504 2166736
18:50:04 54365 8192000000 10259390928 10704187392 1306582672 13.755 96 25 50 1 1325266080 12687872 5995536
18:51:58 38926 8192000000 10260874096 10703568896 1336145520 14.023 98 27 54 0 1353620976 10946768 6528688
18:53:38 52291 8192000000 10261603648 10722234368 1341514992 14.071 99 9 18 0 1355064528 11508912 2040624
18:55:44 73493 8192000000 10261706416 10715279360 1322649824 13.901 97 18 36 1 1342704928 15722592 4332512
18:58:01 47717 8192000000 10280920816 10721624064 1350794720 14.155 99 25 50 2 1373203744 15900912 6508112
18:59:49 52947 8192000000 10267156336 10724859904 1355553200 14.197 99 16 32 0 1373676368 14428512 3694656
19:01:43 91175 8192000000 10270497184 10724605952 1349861504 14.146 99 23 46 0 1369178048 14130032 5186512
19:04:51 23435 8192000000 10271695680 10709856256 1348150080 14.131 99 46 92 2 1370892352 12629296 10112976
19:06:16 121639 8192000000 10275714384 10738958336 1370802992 14.334 100 11 20 1 1391201440 17298784 3099664
19:09:19 89349 8192000000 10264935168 10724343808 1337578944 14.036 97 11 22 0 1346646288 7112608 1954736
19:11:56 71275 8192000000 10275144720 10724311040 1355708848 14.199 99 13 26 0 1374722224 15922256 3091120
19:14:09 54080 8192000000 10273740416 10729304064 1354341200 14.187 98 28 56 0 1370371072 9062496 6967376
19:16:31 142500 8192000000 10309897040 10751258624 1358412080 14.223 98 12 24 0 1374691632 13566176 2713376
19:19:55 66600 8192000000 10318211584 10748399616 1374777536 14.370 99 27 54 0 1392403424 11369504 6256384
19:22:05 38740 8192000000 10265291152 10694426624 1288327152 13.589 94 45 90 0 1310043808 12252864 9463792
19:23:47 22003 8192000000 10277487840 10734120960 1359545280 14.233 100 13 26 0 1388499920 25857072 3097568
19:25:12 101903 8192000000 10277678080 10725257216 1341162064 14.068 98 17 34 0 1359927248 15139296 3625888
19:27:57 97992 8192000000 10261738896 10729037824 1341719152 14.073 98 5 10 0 1357594720 14868944 1006624
19:30:47 75210 8192000000 10276665888 10731544576 1363049088 14.265 99 17 35 0 1374086352 6926464 4110800
19:33:07 87493 8192000000 10285481568 10744918016 1374751568 14.370 99 24 48 1 1391970544 11317072 5901904
19:35:39 92601 8192000000 10281353168 10737205248 1346736032 14.118 97 11 22 1 1361302976 12142960 2423984

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-04-2022 19:39:55 to 01-04-2022 19:40:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-04-2022 19:40:11 to 01-04-2022 19:40:13.

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          240856
MON$OLDEST_ACTIVE               243365
MON$OLDEST_SNAPSHOT             243365
MON$NEXT_TRANSACTION            243365
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                       2020032
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:815: 
:816: mscs-test-161.budzdorov.ru    Fri Apr  1 19:38:18 2022
:817:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:818: 
:819: 
:820: mscs-test-161.budzdorov.ru    Fri Apr  1 19:38:18 2022
:821:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:822: 
:823: 
:824: mscs-test-161.budzdorov.ru    Fri Apr  1 19:38:27 2022
:825:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:826: 
:827: 
:828: mscs-test-161.budzdorov.ru    Fri Apr  1 19:38:27 2022
:829:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:830: 
:831: 
:832: mscs-test-161.budzdorov.ru    Fri Apr  1 19:38:28 2022
:833:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:834: 
:835: 
:836: mscs-test-161.budzdorov.ru    Fri Apr  1 19:38:28 2022
:837:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:838: 
--- end of diff output ---

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

01.04.22 19:40:14. 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-04-01 19:40:16 No severe PSQL-related problems occured
01.04.22 19:40:17. End of report.