Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.2023 22:40
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 15301112 kB
MemAvailable 30393260 kB
Buffers 0 kB
Cached 16437996 kB
SwapCached 4432 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 2023-01-08 22:38:35
DTS_END                         2023-01-08 22:38:35.3480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41182
TRN_ID                          238321
ATT_ID                          663
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9191.43 19632 1102972
customer order: creation 171.93 362 20631
customer order: refuse 14.79 336 1775
order to supplier: creation 46.41 1273 5569
order to supplier: removal 3.46 99375 415
invoice (draft): creation 45.93 3500 5512
invoice (draft): removal 51.28 733 6153
invoice accept: apply 42.04 18494 5045
invoice accept: cancel 46.58 792 5590
customer reserve: creation 3908.73 181 469047
customer reserve: removal 4572.42 59 548690
realization accept: apply 81.88 200 9826
realization accept: cancel 79.26 130 9511
payment from customer: creation 57.51 135 6901
payment from customer: removal 5.63 119 676
payment to supplier: creation 47.78 138 5734
payment to supplier: removal 3.58 60 429
service: total inventory turnovers 4.45 1336 534
service: total monetary turnovers 6.83 25 819
service: aggregate perf. data 0.93 16070 111
service: refresh index statistics 0.03 249312 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-01-2023 22:40:40 to 08-01-2023 22:40:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3109
TEST_TIME 2 3185
TEST_TIME 3 2694
TEST_TIME 4 3618
TEST_TIME 5 4086
TEST_TIME 6 4225
TEST_TIME 7 4701
TEST_TIME 8 4861
TEST_TIME 9 5018
TEST_TIME 10 5304
TEST_TIME 11 5628
TEST_TIME 12 5785
TEST_TIME 13 5906
TEST_TIME 14 6473
TEST_TIME 15 6670
TEST_TIME 16 6946
TEST_TIME 17 7155
TEST_TIME 18 7224
TEST_TIME 19 7647
TEST_TIME 20 8368
TEST_TIME 21 8366
TEST_TIME 22 8699
TEST_TIME 23 8655
TEST_TIME 24 8792
TEST_TIME 25 8926
TEST_TIME 26 9080
TEST_TIME 27 9342
TEST_TIME 28 9871
TEST_TIME 29 10443
TEST_TIME 30 10559
TEST_TIME 31 10304
TEST_TIME 32 10173
TEST_TIME 33 10127
TEST_TIME 34 10279
TEST_TIME 35 10170
TEST_TIME 36 10153
TEST_TIME 37 10069
TEST_TIME 38 9912
TEST_TIME 39 9859
TEST_TIME 40 9873
TEST_TIME 41 9802
TEST_TIME 42 9665
TEST_TIME 43 9624
TEST_TIME 44 9467
TEST_TIME 45 9433
TEST_TIME 46 9482
TEST_TIME 47 9342
TEST_TIME 48 9265
TEST_TIME 49 9369
TEST_TIME 50 9288
TEST_TIME 51 9191
TEST_TIME 52 9262
TEST_TIME 53 9450
TEST_TIME 54 9467
TEST_TIME 55 9497
TEST_TIME 56 9529
TEST_TIME 57 9513
TEST_TIME 58 9591
TEST_TIME 59 9591
TEST_TIME 60 9683
TEST_TIME 61 9630
TEST_TIME 62 9619
TEST_TIME 63 9735
TEST_TIME 64 9711
TEST_TIME 65 9804
TEST_TIME 66 9699
TEST_TIME 67 9625
TEST_TIME 68 9659
TEST_TIME 69 9587
TEST_TIME 70 9566
TEST_TIME 71 9602
TEST_TIME 72 9548
TEST_TIME 73 9520
TEST_TIME 74 9518
TEST_TIME 75 9450
TEST_TIME 76 9373
TEST_TIME 77 9347
TEST_TIME 78 9311
TEST_TIME 79 9269
TEST_TIME 80 9274
TEST_TIME 81 9238
TEST_TIME 82 9220
TEST_TIME 83 9274
TEST_TIME 84 9308
TEST_TIME 85 9278
TEST_TIME 86 9342
TEST_TIME 87 9383
TEST_TIME 88 9399
TEST_TIME 89 9397
TEST_TIME 90 9434
TEST_TIME 91 9403
TEST_TIME 92 9564
TEST_TIME 93 9532
TEST_TIME 94 9441
TEST_TIME 95 9395
TEST_TIME 96 9364
TEST_TIME 97 9328
TEST_TIME 98 9299
TEST_TIME 99 9294
TEST_TIME 100 9275
TEST_TIME 101 9273
TEST_TIME 102 9247
TEST_TIME 103 9235
TEST_TIME 104 9235
TEST_TIME 105 9311
TEST_TIME 106 9333
TEST_TIME 107 9326
TEST_TIME 108 9295
TEST_TIME 109 9398
TEST_TIME 110 9380
TEST_TIME 111 9436
TEST_TIME 112 9432
TEST_TIME 113 9378
TEST_TIME 114 9337
TEST_TIME 115 9287
TEST_TIME 116 9282
TEST_TIME 117 9279
TEST_TIME 118 9231
TEST_TIME 119 9230
TEST_TIME 120 9192

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-01-2023 22:40:44 to 08-01-2023 22:40:47.

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 1537487 1537487 0 0.00 0 26350 6 0 0
doc_list_biud 1537486 1537486 0 0.00 0 27815 27 0 0
sp_add_invoice_to_stock 5186 5045 141 2.71 22 208313 18495 0 141
sp_cancel_adding_invoice 5590 5590 0 0.00 4 60058 792 0 0
sp_cancel_client_order 1775 1775 0 0.00 4 12094 336 0 0
sp_cancel_customer_reserve 548690 548690 0 0.00 0 27820 60 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 13086 119 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 2 1885 60 0 0
sp_cancel_supplier_invoice 6168 6153 15 0.24 2 13428 733 0 15
sp_cancel_supplier_order 415 415 0 0.00 8 1086264 99375 0 0
sp_cancel_write_off 9511 9511 0 0.00 1 23628 130 0 0
sp_client_order 20631 20631 0 0.00 3 30643 362 0 0
sp_customer_reserve 469047 469047 0 0.00 0 52530 182 0 0
sp_fill_shopping_cart 31707 31707 0 0.00 1 14797 40 0 0
sp_fill_shopping_cart_clo_res 469041 469041 0 0.00 0 12031 15 0 0
sp_get_clo_for_invoice 5039 5039 0 0.00 0 39359 351 0 0
sp_kill_cost_storno 16206 16206 0 0.00 0 26343 64 0 0
sp_kill_qstorno_ret_qs2qd 1113459 1113459 0 0.00 0 27814 32 0 0
sp_kill_qty_storno 586998 586998 0 0.00 0 27815 69 0 0
sp_lock_selected_doc 20690 20690 0 0.00 0 6556 4 0 0
sp_make_cost_storno 27500 27500 0 0.00 0 17619 56 0 0
sp_multiply_rows_for_pdistr 27500 27500 0 0.00 0 18701 32 0 0
sp_multiply_rows_for_qdistr 20631 20631 0 0.00 0 15557 162 0 0
sp_pay_from_customer 6901 6901 0 0.00 4 31986 135 0 0
sp_pay_to_supplier 5734 5734 0 0.00 5 22100 138 0 0
sp_payment_common 12635 12635 0 0.00 1 19453 30 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 10230 300 0 0
sp_qd_handle_on_invoice_upd_sts 10628 10628 0 0.00 0 25633 320 0 0
sp_qd_handle_on_reserve_upd_sts 9826 9826 0 0.00 0 12500 46 0 0
sp_reserve_write_off 9826 9826 0 0.00 6 26146 200 0 0
sp_supplier_invoice 5512 5512 0 0.00 7 95970 3501 0 0
sp_supplier_order 5569 5569 0 0.00 5 32165 1273 0 0
srv_aggregate_perf_data 111 111 0 0.00 2796 121454 16070 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1866 153530 46834 0 0
srv_find_qd_qs_mism 1055378 1055378 0 0.00 0 18210 6 0 0
srv_make_invnt_saldo 534 534 0 0.00 40 65498 1336 0 0
srv_make_money_saldo 819 819 0 0.00 0 374 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 153701 348068 249313 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5045 5045 0 0.00 0 23806 172 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 3062 86 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 3599 15 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 693 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 329 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 4880 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 625 12 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 21711 1528 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 1791 6 0 0
t$perf-norm:sp_client_order 20631 20631 0 0.00 0 7554 16 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 4214 23 0 0
t$perf-norm:sp_pay_from_customer 6901 6901 0 0.00 0 5674 7 0 0
t$perf-norm:sp_pay_to_supplier 5734 5734 0 0.00 0 6549 12 0 0
t$perf-norm:sp_reserve_write_off 9826 9826 0 0.00 0 9200 14 0 0
t$perf-norm:sp_supplier_invoice 5512 5512 0 0.00 0 9824 19 0 0
t$perf-norm:sp_supplier_order 5569 5569 0 0.00 0 5854 16 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 312 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 206 7 0 0
t$perf-norm:srv_make_invnt_saldo 534 534 0 0.00 0 820 21 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 4090 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 11 6 0 0
v_add_invoice_to_stock 10358 10076 282 2.72 0 26606 27 0 282
v_all_customers 37264 37264 0 0.00 0 17 1 0 0
v_all_suppliers 30350 30350 0 0.00 0 34 1 0 0
v_all_wares 41262 41262 0 0.00 0 115 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 729 8 0 0
v_cancel_client_order 3550 3550 0 0.00 0 11924 11 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 4738 17 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 2232 5 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 1880 8 0 0
v_cancel_supplier_invoice 870 840 30 3.44 0 8803 24 0 30
v_cancel_supplier_order 830 830 0 0.00 0 2214 8 0 0
v_cancel_write_off 1982 1982 0 0.00 0 20972 32 0 0
v_max_id_clo_ord 5566 5566 0 0.00 0 603 1 0 0
v_max_id_clo_res 956 956 0 0.00 0 1550 8 0 0
v_max_id_ord_sup 5510 5510 0 0.00 0 6972 8 0 0
v_max_non_paid_invoice 1635 1635 0 0.00 0 5818 9 0 0
v_max_non_paid_realizn 3584 3584 0 0.00 0 2831 5 0 0
v_min_id_clo_ord 5566 5566 0 0.00 0 6210 6 0 0
v_min_id_clo_res 956 956 0 0.00 0 198 2 0 0
v_min_id_ord_sup 5510 5510 0 0.00 0 5237 11 0 0
v_min_non_paid_invoice 5734 5734 0 0.00 0 13001 22 0 0
v_min_non_paid_realizn 6901 6901 0 0.00 0 10490 19 0 0
v_our_firm 10632 10632 0 0.00 0 16 1 0 0
v_reserve_write_off 19650 19650 0 0.00 0 25989 17 0 0
x_lock_depdocs_on_canc_invoice 11323 11323 0 0.00 0 49473 138 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 8046 422 0 0
x_make_qstorno_1000_1200 5569 5569 0 0.00 1 32156 1196 0 0
x_make_qstorno_1200_2000 5512 5512 0 0.00 3 95941 3351 0 0
x_make_qstorno_2100_3300 469047 469047 0 0.00 0 52502 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-01-2023 22:40:48 to 08-01-2023 22:40:52.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:38 23 8192000000 8811342800 8826757120 15560368 0.189 4 4 5 0 15788544 88896 139280
20:09:39 16942 8192000000 9889406352 10196709376 966256528 10.550 100 88 176 0 995766112 5642064 23867520
20:11:03 15846 8192000000 9932776704 10250313728 1013940448 11.013 100 89 178 0 1045392960 8066864 23385648
20:12:23 17873 8192000000 9970859456 10302660608 1059389824 11.451 100 77 154 0 1087742480 8794192 19558464
20:13:43 8411 8192000000 10022575520 10368544768 1107381424 11.908 100 78 156 0 1136290832 9972912 18936496
20:14:53 5819 8192000000 10063507792 10414559232 1148324080 12.294 100 84 169 0 1180863648 12976144 19563424
20:16:05 3699 8192000000 10107504768 10470408192 1193295904 12.714 100 81 161 0 1223728304 12918864 17513536
20:17:12 2773 8192000000 10147811024 10518470656 1231039744 13.064 100 87 174 0 1264255568 13840144 19375680
20:18:17 6743 8192000000 10167128784 10545410048 1259030624 13.321 100 78 156 0 1289929072 14740336 16158112
20:19:26 920 8192000000 10212175712 10596036608 1299387248 13.690 100 85 167 1 1331030960 12944272 18699440
20:20:29 2256 8192000000 10224455776 10616565760 1306390208 13.753 100 73 146 0 1338869584 17103056 15376320
20:21:37 6260 8192000000 10186144928 10602823680 1307729232 13.765 100 30 58 1 1321419424 7189152 6501040
20:22:44 4858 8192000000 10207061184 10622312448 1320899792 13.885 100 29 59 0 1333000096 5878048 6222256
20:23:57 6030 8192000000 10204783696 10625040384 1325439504 13.926 100 9 18 0 1332466192 5328144 1698544
20:25:03 8765 8192000000 10214237728 10636197888 1331073712 13.977 100 12 24 0 1339280624 5709056 2497856
20:26:16 38501 8192000000 10226967904 10648891392 1335535872 14.017 100 17 34 0 1344637536 5264576 3837088
20:27:57 9084 8192000000 10215735408 10638274560 1319325136 13.871 99 28 56 0 1332675408 5573360 7776912
20:29:06 5002 8192000000 10205410544 10621710336 1315958336 13.840 100 21 38 1 1326251392 5789360 4503696
20:30:12 12271 8192000000 10155529984 10547200000 1216413056 12.928 94 23 45 0 1226996496 5156368 5427072
20:31:26 23586 8192000000 10161823840 10566905856 1248149248 13.221 98 34 67 0 1261984592 5277840 8557504
20:32:50 6362 8192000000 10141218832 10537160704 1231224352 13.065 99 34 68 0 1245759984 6153232 8382400
20:33:58 11363 8192000000 10160684480 10565042176 1269126928 13.414 100 19 38 1 1278675728 4960624 4588176
20:35:10 71001 8192000000 10177126224 10592276480 1286188336 13.569 100 24 48 0 1297586752 5961712 5436704
20:37:21 7896 8192000000 10190669792 10604564480 1302931472 13.722 100 16 32 0 1311922224 4963664 4027088
20:38:29 14543 8192000000 10229214784 10638557184 1326037040 13.931 100 28 54 2 1337251520 5061872 6152608
20:39:51 79000 8192000000 10250687024 10669314048 1344737888 14.100 100 47 92 0 1364996496 9797888 10460720
20:42:45 48061 8192000000 10277333952 10708135936 1365123200 14.283 100 31 60 1 1392032496 19654064 7255232
20:44:49 5646 8192000000 10296502496 10712190976 1367527888 14.305 99 59 116 0 1397870096 17321824 13020384
20:45:57 11881 8192000000 10314557504 10734755840 1390459392 14.510 100 53 104 1 1421947920 19516448 11972080
20:47:15 20231 8192000000 10290502224 10708570112 1369429392 14.322 98 58 117 0 1407124096 23240160 14454544
20:48:48 12172 8192000000 10322966832 10747990016 1401057472 14.604 100 56 107 1 1437649328 23554112 13037744
20:50:02 6338 8192000000 10324580400 10751705088 1403225872 14.624 100 47 93 0 1438186144 23067552 11892720
20:51:19 1959 8192000000 10358656944 10779013120 1418900016 14.763 100 72 143 1 1464778128 28426528 17451584
20:52:27 1916 8192000000 10360241504 10776702976 1412639680 14.707 99 81 156 1 1457133728 25333792 19160256
20:53:33 1864 8192000000 10370523152 10783154176 1419515920 14.768 100 82 162 1 1469381504 29690448 20175136
20:54:41 2655 8192000000 10368110384 10783207424 1420896240 14.781 100 87 172 1 1472917360 31571344 20449776
20:55:52 5465 8192000000 10344884848 10769555456 1400536080 14.600 100 51 100 0 1448087472 34293104 13258288
20:57:01 7434 8192000000 10340990816 10754179072 1377034688 14.390 99 59 117 1 1436301408 43597776 15668944
20:58:13 3176 8192000000 10353481136 10765037568 1393793952 14.540 100 79 156 1 1456751200 43448320 19508928
20:59:20 16302 8192000000 10326768848 10757443584 1367102416 14.301 98 38 76 1 1413828816 36574848 10151552
21:00:44 5795 8192000000 10330800480 10752684032 1390257920 14.508 100 50 100 1 1438477136 35525616 12693600
21:01:57 21662 8192000000 10309639488 10733883392 1346648896 14.117 97 35 69 0 1393265664 37739792 8876976
21:03:24 24502 8192000000 10304315920 10734096384 1342498096 14.080 98 28 56 0 1393008976 42511904 7998976
21:05:03 16109 8192000000 10293236896 10733760512 1356653136 14.207 100 28 56 0 1408764192 43876992 8234064
21:06:56 17148 8192000000 10277995856 10718609408 1339767792 14.055 100 18 36 0 1384325936 39292864 5265280
21:08:18 5992 8192000000 10303089072 10718367744 1362665440 14.261 100 73 136 1 1391716112 13826576 15224096
21:09:27 64118 8192000000 10321132144 10743431168 1375392112 14.375 100 53 106 0 1413881808 26199216 12290480
21:11:33 66647 8192000000 10288757904 10746806272 1374672000 14.369 100 15 30 0 1403583424 25560608 3350816
21:13:42 79920 8192000000 10274647744 10725068800 1341688816 14.073 97 22 44 1 1359992784 13520416 4783552
21:16:09 65818 8192000000 10280207328 10730864640 1366683744 14.297 99 20 40 1 1379005616 8045984 4275888
21:18:24 26665 8192000000 10274456688 10721505280 1340853904 14.065 97 9 18 0 1353911632 11294048 1763680
21:19:56 58540 8192000000 10300417488 10745655296 1387343552 14.482 100 18 37 1 1402249760 10614736 4291472
21:21:56 106992 8192000000 10308219104 10758467584 1385685392 14.467 99 38 75 0 1401818224 8035696 8097136
21:24:44 49943 8192000000 10298043408 10767192064 1390611728 14.511 99 16 33 0 1410547552 16366592 3569232
21:26:36 27911 8192000000 10315936176 10775203840 1409869136 14.683 100 39 78 0 1441499136 22076080 9553920
21:28:05 54448 8192000000 10327665536 10789761024 1413047616 14.711 100 10 20 0 1435495904 20114864 2333424
21:30:00 53490 8192000000 10328191584 10800078848 1416311888 14.740 100 19 38 0 1448096528 26649648 5134992
21:32:22 73871 8192000000 10315534336 10778357760 1407566416 14.662 100 4 8 0 1428639664 20297136 776112
21:34:49 35444 8192000000 10329176784 10791473152 1421460464 14.786 100 20 40 0 1436695456 10767392 4467600
21:36:31 43876 8192000000 10323886912 10776145920 1405918000 14.648 99 26 52 0 1419658608 8352672 5387936
21:38:17 73600 8192000000 10321131008 10768285696 1371689008 14.342 97 16 32 0 1386273488 11289216 3295264
21:40:32 16815 8192000000 10310677696 10746990592 1366356128 14.294 98 49 95 0 1390279616 13548208 10375280
21:41:52 138050 8192000000 10285082032 10735075328 1239810192 13.144 89 14 27 0 1263318208 20406640 3101376
21:46:14 86857 8192000000 10269418688 10734882816 1342818896 14.083 97 9 18 0 1360694112 16041184 1834032
21:49:16 67286 8192000000 10263098896 10727100416 1332915616 13.993 97 7 14 0 1350447616 16002816 1529184
21:51:30 48738 8192000000 10242621888 10692505600 1316520576 13.845 97 14 28 0 1327448864 7630496 3297792
21:53:29 55620 8192000000 10224548992 10667020288 1238032256 13.128 92 14 28 0 1247223040 6119808 3070976
21:55:31 71648 8192000000 10222743904 10683715584 1324353968 13.916 99 9 18 0 1335108416 8898448 1856000
21:57:43 55126 8192000000 10233697664 10693967872 1332997008 13.994 99 7 14 0 1344345568 9634976 1713584
21:59:39 29085 8192000000 10260929024 10707116032 1342167104 14.077 99 29 54 1 1358709392 10370288 6172000
22:01:09 48061 8192000000 10257388880 10720210944 1358862304 14.227 100 24 48 1 1377622800 13035552 5724944
22:03:07 20119 8192000000 10275241360 10718691328 1358954048 14.228 100 31 62 0 1377326432 11773232 6599152
22:04:27 45347 8192000000 10275280400 10725494784 1352444736 14.169 99 26 52 0 1373080832 14975648 5660448
22:06:13 67168 8192000000 10276596832 10719952896 1326411728 13.935 97 31 62 0 1346492832 13649168 6431936
22:08:23 94089 8192000000 10307238208 10760220672 1391319792 14.518 100 21 42 0 1416384800 20719424 4345584
22:10:57 137630 8192000000 10290325408 10746085376 1352463968 14.170 97 15 30 0 1368448560 12392768 3591824
22:14:36 51671 8192000000 10280499200 10747494400 1393031232 14.533 100 8 17 0 1402125280 6980416 2113632
22:16:36 77705 8192000000 10312786064 10773139456 1404523792 14.635 100 10 21 0 1418357712 11598752 2235168
22:18:55 59272 8192000000 10312434080 10777182208 1385609216 14.467 98 26 53 0 1399980112 8338464 6032432
22:20:55 57482 8192000000 10323674112 10781913088 1416760288 14.744 100 25 50 0 1434937504 12097088 6080128
22:23:04 129365 8192000000 10320808128 10779459584 1396379392 14.563 99 12 24 1 1412718240 14062432 2276416
22:26:27 44685 8192000000 10310300160 10765508608 1398803040 14.584 100 30 60 0 1414804800 9343616 6658144
22:28:12 61443 8192000000 10292643264 10757722112 1362483616 14.260 98 29 59 0 1382163696 13056752 6623328
22:30:43 153528 8192000000 10286637824 10740342784 1320104368 13.878 95 27 54 0 1341771232 15993488 5673376
22:34:18 46929 8192000000 10259383152 10709286912 1312514864 13.809 95 16 29 0 1322935680 6979520 3441296
22:36:22 55519 8192000000 10240745040 10697605120 1329807280 13.965 97 10 20 0 1340681776 8871008 2003488
22:38:17 20127 8192000000 9205287040 9475960832 368522976 4.304 27 3 6 0 373583072 4345664 714432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-01-2023 22:40:54 to 08-01-2023 22:41:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-01-2023 22:41:10 to 08-01-2023 22:41:12.

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          239159
MON$OLDEST_ACTIVE               239160
MON$OLDEST_SNAPSHOT             239160
MON$NEXT_TRANSACTION            239160
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                       2032608
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.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 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 ---
:168: 
:169: mscs-test-161.budzdorov.ru    Sun Jan  8 22:39:08 2023
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:171: 
--- end of diff output ---

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

08.01.23 22:41:13. 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
2023-01-08 22:41:16 No severe PSQL-related problems occured
08.01.23 22:41:17. End of report.