Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.2021 01: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16256476 kB
MemAvailable 30839196 kB
Buffers 0 kB
Cached 15431952 kB
SwapCached 4792 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.8
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 2021-10-28 01:37:52
DTS_END                         2021-10-28 01:37:52.4110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51890
TRN_ID                          237752
ATT_ID                          665
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: 9150.23 23561 1098028
customer order: creation 168.78 372 20254
customer order: refuse 14.37 344 1724
order to supplier: creation 46.78 1330 5613
order to supplier: removal 3.51 97204 421
invoice (draft): creation 45.95 3174 5514
invoice (draft): removal 53.03 743 6363
invoice accept: apply 40.92 18273 4911
invoice accept: cancel 47.60 766 5712
customer reserve: creation 3790.33 180 454840
customer reserve: removal 4647.51 58 557701
realization accept: apply 82.27 163 9872
realization accept: cancel 80.11 109 9613
payment from customer: creation 58.76 113 7051
payment from customer: removal 6.15 85 738
payment to supplier: creation 48.32 138 5798
payment to supplier: removal 3.42 58 410
service: total inventory turnovers 4.85 1110 582
service: total monetary turnovers 6.74 27 809
service: aggregate perf. data 0.82 19102 98
service: refresh index statistics 0.03 327878 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2021 01:39:03 to 28-10-2021 01:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2325
TEST_TIME 2 2956
TEST_TIME 3 3873
TEST_TIME 4 4880
TEST_TIME 5 4241
TEST_TIME 6 4076
TEST_TIME 7 4219
TEST_TIME 8 4450
TEST_TIME 9 4580
TEST_TIME 10 4876
TEST_TIME 11 5310
TEST_TIME 12 5503
TEST_TIME 13 6106
TEST_TIME 14 6636
TEST_TIME 15 6982
TEST_TIME 16 7376
TEST_TIME 17 7749
TEST_TIME 18 8376
TEST_TIME 19 8492
TEST_TIME 20 8425
TEST_TIME 21 8677
TEST_TIME 22 9182
TEST_TIME 23 9194
TEST_TIME 24 10051
TEST_TIME 25 10380
TEST_TIME 26 11046
TEST_TIME 27 10731
TEST_TIME 28 10659
TEST_TIME 29 10581
TEST_TIME 30 10460
TEST_TIME 31 10404
TEST_TIME 32 10255
TEST_TIME 33 10108
TEST_TIME 34 9962
TEST_TIME 35 9787
TEST_TIME 36 9638
TEST_TIME 37 9653
TEST_TIME 38 9617
TEST_TIME 39 9523
TEST_TIME 40 9550
TEST_TIME 41 9529
TEST_TIME 42 9475
TEST_TIME 43 9426
TEST_TIME 44 9418
TEST_TIME 45 9654
TEST_TIME 46 9619
TEST_TIME 47 9687
TEST_TIME 48 9596
TEST_TIME 49 9579
TEST_TIME 50 9575
TEST_TIME 51 9587
TEST_TIME 52 9616
TEST_TIME 53 9549
TEST_TIME 54 9478
TEST_TIME 55 9434
TEST_TIME 56 9392
TEST_TIME 57 9299
TEST_TIME 58 9287
TEST_TIME 59 9361
TEST_TIME 60 9279
TEST_TIME 61 9219
TEST_TIME 62 9147
TEST_TIME 63 9147
TEST_TIME 64 9102
TEST_TIME 65 9097
TEST_TIME 66 9150
TEST_TIME 67 9086
TEST_TIME 68 9149
TEST_TIME 69 9318
TEST_TIME 70 9303
TEST_TIME 71 9294
TEST_TIME 72 9280
TEST_TIME 73 9318
TEST_TIME 74 9384
TEST_TIME 75 9377
TEST_TIME 76 9454
TEST_TIME 77 9410
TEST_TIME 78 9409
TEST_TIME 79 9338
TEST_TIME 80 9285
TEST_TIME 81 9261
TEST_TIME 82 9217
TEST_TIME 83 9197
TEST_TIME 84 9256
TEST_TIME 85 9217
TEST_TIME 86 9242
TEST_TIME 87 9170
TEST_TIME 88 9119
TEST_TIME 89 9158
TEST_TIME 90 9169
TEST_TIME 91 9227
TEST_TIME 92 9233
TEST_TIME 93 9235
TEST_TIME 94 9269
TEST_TIME 95 9297
TEST_TIME 96 9311
TEST_TIME 97 9372
TEST_TIME 98 9335
TEST_TIME 99 9333
TEST_TIME 100 9323
TEST_TIME 101 9349
TEST_TIME 102 9410
TEST_TIME 103 9413
TEST_TIME 104 9399
TEST_TIME 105 9316
TEST_TIME 106 9313
TEST_TIME 107 9254
TEST_TIME 108 9252
TEST_TIME 109 9280
TEST_TIME 110 9247
TEST_TIME 111 9208
TEST_TIME 112 9181
TEST_TIME 113 9141
TEST_TIME 114 9140
TEST_TIME 115 9126
TEST_TIME 116 9137
TEST_TIME 117 9144
TEST_TIME 118 9176
TEST_TIME 119 9204
TEST_TIME 120 9156

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-10-2021 01:39:07 to 28-10-2021 01:39:10.

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 1519153 1519153 0 0.00 0 30461 6 0 0
doc_list_biud 1519153 1519153 0 0.00 0 82279 27 0 0
sp_add_invoice_to_stock 5024 4911 113 2.24 37 191352 18274 0 113
sp_cancel_adding_invoice 5712 5712 0 0.00 5 79834 766 0 0
sp_cancel_client_order 1724 1724 0 0.00 4 13830 344 0 0
sp_cancel_customer_reserve 557701 557701 0 0.00 1 82281 58 0 0
sp_cancel_pay_from_customer 738 738 0 0.00 3 6285 86 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 2 4656 58 0 0
sp_cancel_supplier_invoice 6374 6363 11 0.17 2 14338 744 0 11
sp_cancel_supplier_order 421 421 0 0.00 12 989564 97204 0 0
sp_cancel_write_off 9613 9613 0 0.00 1 9123 110 0 0
sp_client_order 20254 20254 0 0.00 3 16221 373 0 0
sp_customer_reserve 454840 454840 0 0.00 0 110775 180 0 0
sp_fill_shopping_cart 31379 31379 0 0.00 1 17838 41 0 0
sp_fill_shopping_cart_clo_res 454835 454835 0 0.00 0 17944 14 0 0
sp_get_clo_for_invoice 4899 4899 0 0.00 0 26038 301 0 0
sp_kill_cost_storno 16473 16473 0 0.00 0 21512 55 0 0
sp_kill_qstorno_ret_qs2qd 1131799 1131799 0 0.00 0 82254 31 0 0
sp_kill_qty_storno 596302 596302 0 0.00 0 82278 67 0 0
sp_lock_selected_doc 20544 20544 0 0.00 0 7942 5 0 0
sp_make_cost_storno 27617 27617 0 0.00 0 30458 57 0 0
sp_multiply_rows_for_pdistr 27617 27617 0 0.00 0 9601 30 0 0
sp_multiply_rows_for_qdistr 20254 20254 0 0.00 0 11666 185 0 0
sp_pay_from_customer 7051 7051 0 0.00 4 23037 114 0 0
sp_pay_to_supplier 5798 5798 0 0.00 4 12918 139 0 0
sp_payment_common 12849 12849 0 0.00 2 22883 29 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 13824 313 0 0
sp_qd_handle_on_invoice_upd_sts 10608 10608 0 0.00 0 18672 310 0 0
sp_qd_handle_on_reserve_upd_sts 9872 9872 0 0.00 0 13811 54 0 0
sp_reserve_write_off 9872 9872 0 0.00 5 13826 164 0 0
sp_supplier_invoice 5514 5514 0 0.00 6 112698 3174 0 0
sp_supplier_order 5613 5613 0 0.00 5 46571 1331 0 0
srv_aggregate_perf_data 98 98 0 0.00 2831 93932 19103 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1727 174584 50465 0 0
srv_find_qd_qs_mism 1050730 1050730 0 0.00 0 19799 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 64 86230 1111 0 0
srv_make_money_saldo 809 809 0 0.00 0 2805 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 213456 560904 327879 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4911 4911 0 0.00 0 24979 230 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 20506 164 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 1542 12 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 3375 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 738 738 0 0.00 0 433 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1446 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 7475 58 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 43761 1868 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 3561 18 0 0
t$perf-norm:sp_client_order 20254 20254 0 0.00 0 14404 21 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 935 4 0 0
t$perf-norm:sp_pay_from_customer 7051 7051 0 0.00 0 20437 15 0 0
t$perf-norm:sp_pay_to_supplier 5798 5798 0 0.00 0 20431 19 0 0
t$perf-norm:sp_reserve_write_off 9872 9872 0 0.00 0 21730 20 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 9909 24 0 0
t$perf-norm:sp_supplier_order 5613 5613 0 0.00 0 6783 24 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 343 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 182 5 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 4703 36 0 0
t$perf-norm:srv_make_money_saldo 809 809 0 0.00 0 1146 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10018 9792 226 2.25 0 4515 18 0 226
v_all_customers 36800 36800 0 0.00 0 24 1 0 0
v_all_suppliers 30574 30574 0 0.00 0 24 1 0 0
v_all_wares 40508 40508 0 0.00 0 133 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 71 5 0 0
v_cancel_client_order 3448 3448 0 0.00 0 2798 6 0 0
v_cancel_customer_prepayment 1476 1476 0 0.00 0 5277 10 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 1490 6 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 1385 7 0 0
v_cancel_supplier_invoice 820 798 22 2.68 0 2526 20 0 22
v_cancel_supplier_order 842 842 0 0.00 0 1064 6 0 0
v_cancel_write_off 2008 2008 0 0.00 0 2137 13 0 0
v_max_id_clo_ord 5613 5613 0 0.00 0 10758 4 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 3670 14 0 0
v_max_id_ord_sup 5512 5512 0 0.00 0 3565 4 0 0
v_max_non_paid_invoice 1636 1636 0 0.00 0 3074 6 0 0
v_max_non_paid_realizn 3855 3855 0 0.00 0 4113 5 0 0
v_min_id_clo_ord 5613 5613 0 0.00 0 2144 6 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 1154 4 0 0
v_min_id_ord_sup 5512 5512 0 0.00 0 6718 10 0 0
v_min_non_paid_invoice 5798 5798 0 0.00 0 9806 21 0 0
v_min_non_paid_realizn 7051 7051 0 0.00 0 22876 19 0 0
v_our_firm 10100 10100 0 0.00 0 24 1 0 0
v_reserve_write_off 19744 19744 0 0.00 0 4699 7 0 0
x_lock_depdocs_on_canc_invoice 11676 11676 0 0.00 0 27526 141 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 16814 414 0 0
x_make_qstorno_1000_1200 5613 5613 0 0.00 2 46562 1249 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 2 111414 3027 0 0
x_make_qstorno_2100_3300 454840 454840 0 0.00 0 110773 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2021 01:39:11 to 28-10-2021 01:39:15.

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:07:56 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:57 20058 8192000000 9892047648 10198921216 966155024 10.549 100 87 174 0 995047376 5508864 23383488
23:10:24 19366 8192000000 9929148608 10249125888 1011550864 10.990 100 85 170 1 1041322576 7716896 22054816
23:11:57 12406 8192000000 9973306064 10307108864 1052841936 11.388 100 73 146 0 1079843968 8877408 18124624
23:13:18 10331 8192000000 10044407200 10395312128 1129462432 12.116 100 79 158 0 1158878816 11216928 18199456
23:14:34 6852 8192000000 10101061440 10463543296 1185780304 12.644 100 85 170 1 1218630880 13148368 19702208
23:15:43 6426 8192000000 10138463200 10511425536 1226682176 13.023 100 80 159 0 1257298400 13264448 17351776
23:16:52 4283 8192000000 10170233872 10556784640 1266293616 13.388 100 72 144 0 1294377504 11739392 16344496
23:17:59 2393 8192000000 10196352144 10581192704 1284131424 13.551 100 91 178 2 1318116704 13868464 20116816
23:19:03 2646 8192000000 10215729424 10604269568 1300343312 13.698 100 84 168 1 1332857552 12867248 19646992
23:20:07 3749 8192000000 10207667680 10614177792 1314166912 13.824 100 57 111 2 1336482480 10016800 12298768
23:21:14 5946 8192000000 10212616624 10620190720 1316709616 13.847 100 35 71 2 1331403696 6617008 8077072
23:22:20 74300 8192000000 10209767072 10634776576 1326758288 13.938 100 22 44 0 1337394592 5539040 5097264
23:24:36 3198 8192000000 10219488080 10645344256 1331684864 13.982 100 28 56 0 1344453696 6056864 6711968
23:25:43 7182 8192000000 10230856064 10650988544 1338401504 14.043 100 22 44 1 1349684720 6446768 4836448
23:26:51 15853 8192000000 10220536384 10637692928 1309676576 13.783 98 30 60 1 1322927040 5991712 7258752
23:28:08 20644 8192000000 10196050464 10617122816 1272821088 13.447 96 16 32 0 1281836480 5929312 3086080
23:29:29 4956 8192000000 10183238176 10587811840 1282155920 13.533 100 29 58 1 1298642096 10508944 5977232
23:30:34 9828 8192000000 10160014624 10559225856 1231829136 13.071 96 17 33 2 1241251600 4982320 4440144
23:31:44 62271 8192000000 10122331104 10510548992 1159266096 12.396 93 25 50 0 1170559584 4698864 6594624
23:33:47 7569 8192000000 10162262128 10568044544 1260841712 13.338 99 35 70 2 1276212128 7304784 8065632
23:34:59 11507 8192000000 10176511600 10589679616 1291503584 13.618 100 24 48 0 1302183024 5346256 5333184
23:36:12 12258 8192000000 10184926752 10599079936 1297324144 13.671 100 22 44 1 1308344672 6105344 4915184
23:37:24 57681 8192000000 10213499184 10623156224 1313086320 13.814 100 41 81 2 1328049296 5416960 9546016
23:39:30 8437 8192000000 10258882560 10665320448 1341596784 14.072 100 56 108 1 1366571104 11678880 13295440
23:40:43 8367 8192000000 10273665584 10688729088 1363679072 14.270 100 45 89 0 1387436496 14162848 9594576
23:41:54 18023 8192000000 10286795744 10708414464 1377347808 14.393 100 49 97 1 1403836928 14564128 11924992
23:43:19 21825 8192000000 10315247872 10731950080 1390135968 14.507 100 54 108 3 1428479232 25644192 12699072
23:44:56 12889 8192000000 10318090752 10740117504 1400709184 14.601 100 42 85 4 1432430848 21034208 10687456
23:46:16 7572 8192000000 10336504544 10759680000 1411664272 14.699 100 53 105 0 1446620848 22796208 12160368
23:47:29 7242 8192000000 10357630080 10776883200 1419097376 14.765 100 68 134 2 1465851568 30653120 16101072
23:48:39 5306 8192000000 10342604288 10761814016 1401605936 14.609 99 54 107 2 1452011072 36588896 13816240
23:49:49 11272 8192000000 10348769792 10770026496 1395707408 14.557 99 69 138 1 1458259792 44688352 17864032
23:51:08 3822 8192000000 10351923408 10766798848 1392729584 14.530 100 78 155 1 1456360656 43966944 19664128
23:52:19 4021 8192000000 10342740048 10762534912 1368320560 14.312 98 74 148 1 1433183504 45807728 19055216
23:53:30 2932 8192000000 10343637760 10758602752 1386477696 14.474 100 59 118 0 1445019712 43124432 15417584
23:54:39 1725 8192000000 10365513712 10775416832 1390885328 14.514 100 88 174 1 1460690704 47647520 22157856
23:55:45 7895 8192000000 10340187760 10765213696 1375245312 14.374 98 50 100 0 1427581056 39501792 12833952
23:56:56 9923 8192000000 10340442816 10769530880 1379250688 14.410 98 37 72 1 1429847216 40390224 10206304
23:58:09 8797 8192000000 10324105424 10751598592 1370798960 14.334 99 42 84 1 1425253552 43298304 11156288
23:59:21 29194 8192000000 10309293632 10748923904 1373155120 14.355 100 23 46 4 1424871728 45120880 6595728
00:01:12 22365 8192000000 10303997568 10746314752 1343756592 14.091 98 24 48 0 1395178048 44610688 6810768
00:02:39 22727 8192000000 10283701616 10730680320 1356513616 14.206 100 15 30 1 1388801472 27997744 4290112
00:04:02 30784 8192000000 10273531856 10711834624 1347598352 14.126 99 37 73 2 1363602432 7500384 8503696
00:05:35 18853 8192000000 10272670480 10712154112 1346205696 14.113 99 35 70 1 1364649328 11066496 7377136
00:06:57 67901 8192000000 10262767392 10706493440 1322991968 13.904 97 31 59 0 1338267760 9645696 5630096
00:09:06 139219 8192000000 10294276592 10737811456 1359146912 14.230 98 18 36 0 1374754816 11634352 3973552
00:12:55 141282 8192000000 10269127216 10740862976 1376056928 14.381 100 25 50 0 1397168928 14132352 6979648
00:16:24 44455 8192000000 10283335280 10726563840 1328063168 13.950 96 12 24 0 1338771648 8161024 2547456
00:18:13 37221 8192000000 10275595264 10723565568 1337568928 14.035 97 30 59 1 1357198736 13019584 6610224
00:20:23 51868 8192000000 10304595200 10758909952 1387183344 14.481 100 18 36 0 1411105552 19279616 4642592
00:22:15 65804 8192000000 10310268496 10771824640 1388849376 14.496 100 11 22 0 1414227648 22447232 2931040
00:24:34 68531 8192000000 10296271440 10755399680 1356781072 14.208 97 13 26 1 1373336304 13831328 2723904
00:26:44 18341 8192000000 10322110032 10755358720 1379725648 14.414 99 36 72 0 1395436704 7387520 8323536
00:28:03 47435 8192000000 10281660448 10737954816 1354782880 14.190 98 15 30 1 1373795392 15483760 3528752
00:29:50 40372 8192000000 10304717920 10744467456 1363512784 14.269 98 25 48 1 1378203056 9028528 5661744
00:31:33 85927 8192000000 10294135040 10731761664 1373906480 14.362 99 31 60 1 1390515152 8894112 7714560
00:34:07 39381 8192000000 10293189520 10737504256 1360866864 14.245 98 35 70 1 1382763856 14327696 7569296
00:35:47 65916 8192000000 10266258336 10738397184 1368532912 14.314 99 12 24 2 1384728544 13358272 2837360
00:37:53 86116 8192000000 10276677568 10736418816 1366514816 14.296 98 16 33 0 1378331728 8384528 3432384
00:40:22 63738 8192000000 10255131952 10707697664 1304634960 13.737 94 5 10 1 1311407216 5878608 893648
00:42:33 70613 8192000000 10284891984 10740391936 1371240384 14.338 99 23 46 3 1390140224 13187056 5712784
00:44:58 41568 8192000000 10286947840 10739208192 1333932496 14.003 96 26 52 0 1359696592 19329840 6434256
00:46:43 52499 8192000000 10284298208 10727763968 1348735600 14.136 98 18 36 1 1368287600 15795312 3756688
00:48:36 54925 8192000000 10290588496 10726768640 1350650512 14.153 98 33 66 1 1372560416 14990480 6919424
00:50:31 64379 8192000000 10284440624 10732486656 1346841536 14.119 98 22 44 2 1369147632 17655664 4650432
00:52:38 174583 8192000000 10273292432 10721169408 1284320368 13.552 93 12 24 1 1302238080 15758656 2159056
00:56:33 93718 8192000000 10243188512 10691301376 1314194304 13.824 97 21 42 0 1328660192 9424176 5041712
00:59:07 49977 8192000000 10262496704 10694807552 1326451024 13.935 97 33 66 0 1341939136 8424224 7063888
01:00:59 51701 8192000000 10249474272 10702061568 1323660544 13.910 98 16 32 0 1345953184 17867024 4425616
01:02:51 40323 8192000000 10242827568 10700062720 1341944960 14.075 100 7 14 1 1359178224 16067568 1165696
01:04:34 93638 8192000000 10273420368 10726141952 1355891232 14.200 100 20 40 0 1377186592 16654304 4641056
01:07:10 92431 8192000000 10277272000 10730561536 1352168080 14.167 99 19 39 1 1376504080 20477744 3858256
01:09:48 57216 8192000000 10275371872 10724225024 1347432368 14.124 98 16 32 0 1361046400 10282464 3331568
01:11:48 68188 8192000000 10286875792 10749419520 1370793536 14.334 99 11 22 0 1386528992 13276592 2458864
01:14:00 70744 8192000000 10310876608 10768175104 1395603744 14.556 100 19 38 0 1411305616 11254608 4447264
01:16:12 55506 8192000000 10310145104 10753744896 1365437792 14.286 98 32 64 0 1382623904 10265504 6920608
01:18:17 55346 8192000000 10288938640 10745524224 1340520352 14.062 97 27 54 1 1359102560 12867168 5715040
01:20:24 151135 8192000000 10293868176 10748895232 1332581120 13.990 96 23 46 1 1354828000 16884784 5362096
01:24:19 26059 8192000000 10290360976 10752602112 1383921760 14.452 100 10 20 1 1400266256 13862800 2481696
01:25:45 80005 8192000000 10270758064 10742595584 1362279744 14.258 99 8 16 0 1378324656 14060528 1984384
01:28:07 65278 8192000000 10268458528 10723962880 1345371328 14.106 97 10 20 1 1354511392 6937008 2203056
01:30:13 87328 8192000000 10275330656 10735763456 1362527200 14.260 99 17 34 0 1378819936 12321552 3971184
01:32:42 63905 8192000000 10283061168 10742063104 1352576192 14.171 98 21 42 0 1372374800 14970288 4828320
01:34:46 91113 8192000000 10272166784 10735992832 1327961856 13.949 96 9 18 0 1347197488 17036448 2199184
01:37:18 41893 8192000000 8895394448 9054138368 69728160 0.843 6 2 4 0 73514976 3443776 343040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-10-2021 01:39:17 to 28-10-2021 01:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-10-2021 01:39:33 to 28-10-2021 01:39:35.

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          238047
MON$OLDEST_ACTIVE               238048
MON$OLDEST_SNAPSHOT             238048
MON$NEXT_TRANSACTION            238048
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                       2009856
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
--- end of diff output ---

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

28.10.21 01:39:36. 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
2021-10-28 01:39:38 No severe PSQL-related problems occured
28.10.21 01:39:39. End of report.