Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2022 22: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 15216528 kB
MemAvailable 30090612 kB
Buffers 0 kB
Cached 16483280 kB
SwapCached 3460 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-27 22:38:32
DTS_END                         2022-12-27 22:38:32.0510
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/53610
TRN_ID                          237329
ATT_ID                          619
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: 9215.33 24403 1105839
customer order: creation 163.68 347 19641
customer order: refuse 13.90 327 1668
order to supplier: creation 44.18 1134 5302
order to supplier: removal 3.34 90340 401
invoice (draft): creation 44.14 3388 5297
invoice (draft): removal 51.83 691 6220
invoice accept: apply 40.45 18418 4854
invoice accept: cancel 47.05 689 5646
customer reserve: creation 3829.48 178 459537
customer reserve: removal 4690.45 50 562854
realization accept: apply 79.22 175 9506
realization accept: cancel 84.98 104 10198
payment from customer: creation 56.28 118 6754
payment from customer: removal 5.48 90 658
payment to supplier: creation 46.14 142 5537
payment to supplier: removal 3.34 76 401
service: total inventory turnovers 4.38 1023 525
service: total monetary turnovers 6.21 29 745
service: aggregate perf. data 0.76 20981 91
service: refresh index statistics 0.03 349751 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-12-2022 22:39:39 to 27-12-2022 22:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2971
TEST_TIME 2 2609
TEST_TIME 3 3109
TEST_TIME 4 3592
TEST_TIME 5 3724
TEST_TIME 6 4260
TEST_TIME 7 4872
TEST_TIME 8 4831
TEST_TIME 9 5290
TEST_TIME 10 5234
TEST_TIME 11 5802
TEST_TIME 12 6081
TEST_TIME 13 6194
TEST_TIME 14 6782
TEST_TIME 15 6737
TEST_TIME 16 7912
TEST_TIME 17 8292
TEST_TIME 18 8497
TEST_TIME 19 8529
TEST_TIME 20 9109
TEST_TIME 21 9686
TEST_TIME 22 9993
TEST_TIME 23 10518
TEST_TIME 24 10215
TEST_TIME 25 10159
TEST_TIME 26 9862
TEST_TIME 27 9717
TEST_TIME 28 10009
TEST_TIME 29 10244
TEST_TIME 30 10027
TEST_TIME 31 9940
TEST_TIME 32 9855
TEST_TIME 33 9819
TEST_TIME 34 9913
TEST_TIME 35 9806
TEST_TIME 36 9650
TEST_TIME 37 9598
TEST_TIME 38 9580
TEST_TIME 39 9355
TEST_TIME 40 9444
TEST_TIME 41 9344
TEST_TIME 42 9240
TEST_TIME 43 9115
TEST_TIME 44 9086
TEST_TIME 45 9005
TEST_TIME 46 9065
TEST_TIME 47 9085
TEST_TIME 48 9010
TEST_TIME 49 9085
TEST_TIME 50 9060
TEST_TIME 51 9076
TEST_TIME 52 9201
TEST_TIME 53 9247
TEST_TIME 54 9262
TEST_TIME 55 9338
TEST_TIME 56 9345
TEST_TIME 57 9317
TEST_TIME 58 9434
TEST_TIME 59 9419
TEST_TIME 60 9361
TEST_TIME 61 9268
TEST_TIME 62 9256
TEST_TIME 63 9201
TEST_TIME 64 9134
TEST_TIME 65 9049
TEST_TIME 66 8980
TEST_TIME 67 9158
TEST_TIME 68 9214
TEST_TIME 69 9255
TEST_TIME 70 9323
TEST_TIME 71 9331
TEST_TIME 72 9306
TEST_TIME 73 9279
TEST_TIME 74 9268
TEST_TIME 75 9320
TEST_TIME 76 9296
TEST_TIME 77 9332
TEST_TIME 78 9378
TEST_TIME 79 9464
TEST_TIME 80 9352
TEST_TIME 81 9297
TEST_TIME 82 9288
TEST_TIME 83 9209
TEST_TIME 84 9196
TEST_TIME 85 9265
TEST_TIME 86 9226
TEST_TIME 87 9227
TEST_TIME 88 9261
TEST_TIME 89 9275
TEST_TIME 90 9309
TEST_TIME 91 9325
TEST_TIME 92 9319
TEST_TIME 93 9348
TEST_TIME 94 9386
TEST_TIME 95 9402
TEST_TIME 96 9433
TEST_TIME 97 9384
TEST_TIME 98 9436
TEST_TIME 99 9486
TEST_TIME 100 9532
TEST_TIME 101 9450
TEST_TIME 102 9403
TEST_TIME 103 9365
TEST_TIME 104 9305
TEST_TIME 105 9285
TEST_TIME 106 9277
TEST_TIME 107 9258
TEST_TIME 108 9256
TEST_TIME 109 9211
TEST_TIME 110 9181
TEST_TIME 111 9176
TEST_TIME 112 9144
TEST_TIME 113 9163
TEST_TIME 114 9181
TEST_TIME 115 9168
TEST_TIME 116 9162
TEST_TIME 117 9192
TEST_TIME 118 9236
TEST_TIME 119 9222
TEST_TIME 120 9219

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-12-2022 22:39:42 to 27-12-2022 22:39:46.

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 1529886 1529886 0 0.00 0 19327 6 0 0
doc_list_biud 1529885 1529885 0 0.00 0 28386 24 0 0
sp_add_invoice_to_stock 4982 4854 128 2.56 39 167287 18419 0 128
sp_cancel_adding_invoice 5646 5646 0 0.00 4 76555 690 0 0
sp_cancel_client_order 1668 1668 0 0.00 4 7824 327 0 0
sp_cancel_customer_reserve 562854 562854 0 0.00 0 21597 50 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 9103 90 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 2 3137 77 0 0
sp_cancel_supplier_invoice 6233 6220 13 0.20 1 19715 692 0 13
sp_cancel_supplier_order 401 401 0 0.00 6 863702 90341 0 0
sp_cancel_write_off 10198 10198 0 0.00 1 11554 104 0 0
sp_client_order 19641 19641 0 0.00 3 27609 347 0 0
sp_customer_reserve 459537 459537 0 0.00 0 74244 179 0 0
sp_fill_shopping_cart 30236 30236 0 0.00 1 38889 39 0 0
sp_fill_shopping_cart_clo_res 459528 459528 0 0.00 0 16876 15 0 0
sp_get_clo_for_invoice 4845 4845 0 0.00 0 23512 335 0 0
sp_kill_cost_storno 16903 16903 0 0.00 0 11470 49 0 0
sp_kill_qstorno_ret_qs2qd 1142527 1142527 0 0.00 0 21557 27 0 0
sp_kill_qty_storno 601337 601337 0 0.00 0 28383 60 0 0
sp_lock_selected_doc 19860 19860 0 0.00 0 9071 4 0 0
sp_make_cost_storno 26642 26642 0 0.00 0 17791 60 0 0
sp_multiply_rows_for_pdistr 26641 26641 0 0.00 0 11907 26 0 0
sp_multiply_rows_for_qdistr 19641 19641 0 0.00 0 13737 167 0 0
sp_pay_from_customer 6754 6754 0 0.00 4 11970 119 0 0
sp_pay_to_supplier 5537 5537 0 0.00 4 17756 142 0 0
sp_payment_common 12291 12291 0 0.00 1 17185 34 0 0
sp_qd_handle_on_cancel_clo 1668 1668 0 0.00 0 7816 300 0 0
sp_qd_handle_on_invoice_upd_sts 10490 10490 0 0.00 0 28377 310 0 0
sp_qd_handle_on_reserve_upd_sts 9506 9506 0 0.00 0 10276 54 0 0
sp_reserve_write_off 9506 9506 0 0.00 6 19795 176 0 0
sp_supplier_invoice 5297 5297 0 0.00 6 96290 3388 0 0
sp_supplier_order 5302 5302 0 0.00 4 32429 1134 0 0
srv_aggregate_perf_data 91 91 0 0.00 2961 209950 20981 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1782 119305 48307 0 0
srv_find_qd_qs_mism 1058938 1058938 0 0.00 0 12742 6 0 0
srv_make_invnt_saldo 525 525 0 0.00 30 73831 1023 0 0
srv_make_money_saldo 745 745 0 0.00 0 6087 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 293018 455462 349751 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4854 4854 0 0.00 0 11531 175 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 9332 110 0 0
t$perf-norm:sp_cancel_client_order 1668 1668 0 0.00 0 4100 17 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 7733 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 5127 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 553 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 3143 26 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 25771 1675 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 2796 8 0 0
t$perf-norm:sp_client_order 19641 19641 0 0.00 0 8890 18 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 2419 10 0 0
t$perf-norm:sp_pay_from_customer 6754 6754 0 0.00 0 10450 20 0 0
t$perf-norm:sp_pay_to_supplier 5537 5537 0 0.00 0 5580 9 0 0
t$perf-norm:sp_reserve_write_off 9506 9506 0 0.00 0 5902 14 0 0
t$perf-norm:sp_supplier_invoice 5297 5297 0 0.00 0 5674 20 0 0
t$perf-norm:sp_supplier_order 5302 5302 0 0.00 0 5447 15 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 216 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 12 1 0 0
t$perf-norm:srv_make_invnt_saldo 525 525 0 0.00 0 963 20 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 2749 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9944 9688 256 2.57 0 4847 19 0 256
v_all_customers 35510 35510 0 0.00 0 61 1 0 0
v_all_suppliers 29224 29224 0 0.00 0 23 1 0 0
v_all_wares 39282 39282 0 0.00 0 103 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 2509 12 0 0
v_cancel_client_order 3336 3336 0 0.00 0 4159 6 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 3697 10 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 446 3 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1614 7 0 0
v_cancel_supplier_invoice 758 732 26 3.43 0 991 17 0 26
v_cancel_supplier_order 802 802 0 0.00 0 984 6 0 0
v_cancel_write_off 1912 1912 0 0.00 0 3041 21 0 0
v_max_id_clo_ord 5302 5302 0 0.00 0 2590 2 0 0
v_max_id_clo_res 943 943 0 0.00 0 2736 11 0 0
v_max_id_ord_sup 5293 5293 0 0.00 0 6528 3 0 0
v_max_non_paid_invoice 1520 1520 0 0.00 0 180 2 0 0
v_max_non_paid_realizn 3669 3669 0 0.00 0 5043 4 0 0
v_min_id_clo_ord 5302 5302 0 0.00 0 12956 7 0 0
v_min_id_clo_res 943 943 0 0.00 0 3997 15 0 0
v_min_id_ord_sup 5293 5293 0 0.00 0 7953 9 0 0
v_min_non_paid_invoice 5537 5537 0 0.00 0 17174 30 0 0
v_min_non_paid_realizn 6754 6754 0 0.00 0 11895 27 0 0
v_our_firm 9942 9942 0 0.00 0 26 1 0 0
v_reserve_write_off 19012 19012 0 0.00 0 5772 8 0 0
x_lock_depdocs_on_canc_invoice 11500 11500 0 0.00 0 24641 111 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 2393 286 0 0
x_make_qstorno_1000_1200 5302 5302 0 0.00 1 32421 1059 0 0
x_make_qstorno_1200_2000 5297 5297 0 0.00 3 96274 3244 0 0
x_make_qstorno_2100_3300 459537 459537 0 0.00 0 74241 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2022 22:39:47 to 27-12-2022 22:39:51.

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:35 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:37 1711 8192000000 9883971920 10187665408 972363184 10.610 100 90 180 0 1001392880 5553808 23475888
20:10:45 20742 8192000000 9919208224 10234621952 999236400 10.871 100 91 182 0 1031518576 8014304 24267872
20:12:12 17051 8192000000 9956771600 10281836544 1039913680 11.264 100 91 181 0 1072590720 9528640 23148400
20:13:34 6110 8192000000 9998634224 10338066432 1085276560 11.698 100 81 162 0 1115574688 10590496 19707632
20:14:46 5419 8192000000 10049004144 10402914304 1136668208 12.184 100 81 161 0 1168618384 12341664 19608512
20:15:54 2896 8192000000 10111757552 10475831296 1198189792 12.760 100 86 162 0 1228593904 12360144 18043968
20:17:00 2575 8192000000 10145069616 10519085056 1236464064 13.114 100 79 156 0 1267383376 13734240 17185072
20:18:05 1435 8192000000 10192090992 10568593408 1270023232 13.422 100 89 180 0 1303914048 13726160 20164656
20:19:08 1167 8192000000 10210902448 10595627008 1299177536 13.688 100 92 182 1 1333473408 13916464 20379408
20:20:10 6024 8192000000 10209210016 10604085248 1303328272 13.725 100 68 137 0 1326809264 6830144 16650848
20:21:17 4415 8192000000 10204062576 10609451008 1307871040 13.767 100 35 67 2 1323911904 8321376 7719488
20:22:22 8272 8192000000 10199113024 10615783424 1312419152 13.808 100 18 37 0 1322344896 6021888 3903856
20:23:32 12347 8192000000 10200987408 10622603264 1318250336 13.861 100 16 31 1 1327256528 5393968 3612224
20:24:48 9262 8192000000 10223450720 10641293312 1332668240 13.991 100 35 69 0 1347033760 6661200 7704320
20:25:57 11074 8192000000 10211021328 10635120640 1324351248 13.916 100 28 56 0 1337306944 6318128 6637568
20:27:09 5602 8192000000 10229565632 10653499392 1337358272 14.034 100 25 50 0 1349692224 6899616 5434336
20:28:15 7241 8192000000 10225067648 10643505152 1327099312 13.941 100 30 60 0 1349325632 15736224 6490096
20:29:26 21034 8192000000 10151784736 10555076608 1195208048 12.732 92 28 55 0 1206932048 5234800 6489200
20:30:52 8821 8192000000 10136928816 10538090496 1202433280 12.799 95 26 51 0 1213186896 5660800 5092816
20:32:03 5371 8192000000 10142210864 10539257856 1235198336 13.102 99 34 68 0 1248949088 6080464 7670288
20:33:08 4823 8192000000 10153879872 10551877632 1252237280 13.259 99 35 68 0 1265577856 5617632 7722944
20:34:16 61157 8192000000 10173910800 10585423872 1279563200 13.509 100 33 66 0 1291926544 5225968 7137376
20:36:18 5913 8192000000 10188774560 10600058880 1296961936 13.668 100 27 54 1 1308940256 5387200 6591120
20:37:24 6115 8192000000 10198292288 10614304768 1304869632 13.739 100 16 32 0 1313655584 5159152 3626800
20:38:30 35122 8192000000 10229853232 10635960320 1326649984 13.937 100 33 64 0 1339216352 5353776 7212592
20:40:24 96260 8192000000 10260889120 10683129856 1338213568 14.041 99 29 57 0 1362561984 18373568 5974848
20:43:26 48413 8192000000 10281160816 10710368256 1360022624 14.238 99 28 56 0 1386949328 19773376 7153328
20:45:18 2075 8192000000 10320252400 10735771648 1385827056 14.469 100 65 128 0 1426389888 24808704 15754128
20:46:29 6157 8192000000 10325795936 10742669312 1395560704 14.555 100 65 128 0 1437692176 27108304 15023168
20:47:47 8900 8192000000 10329376416 10750787584 1391948960 14.523 99 56 110 1 1435365376 29958224 13458192
20:49:00 11118 8192000000 10355096176 10774708224 1415009104 14.728 100 55 110 0 1462773360 34129168 13635088
20:50:19 4648 8192000000 10348253008 10764845056 1402115824 14.614 99 63 126 1 1450549808 32436288 15997696
20:51:34 3734 8192000000 10343084992 10761736192 1401305584 14.607 100 58 115 1 1452036864 35343648 15387632
20:52:46 2525 8192000000 10346443760 10759643136 1400592784 14.600 100 75 150 2 1454856064 35027344 19235936
20:53:55 1780 8192000000 10344362928 10748776448 1380674576 14.423 100 87 173 1 1445064400 42595888 21793936
20:55:01 3465 8192000000 10320843776 10731565056 1376112256 14.382 100 72 143 1 1426743440 33303424 17327760
20:56:09 10670 8192000000 10296588080 10721406976 1356886768 14.209 99 30 60 0 1395785760 30548912 8350080
20:57:21 13136 8192000000 10289353632 10719379456 1338207488 14.041 98 22 44 0 1383087760 38727312 6152960
20:58:37 11781 8192000000 10278476080 10698485760 1337422912 14.034 99 37 74 0 1377270160 30178624 9668624
20:59:54 9340 8192000000 10250423168 10684317696 1331878336 13.984 100 33 63 0 1369177904 29576816 7722752
21:01:09 39417 8192000000 10252324720 10682970112 1301348544 13.708 98 33 63 0 1340692624 31362976 7981104
21:02:55 56907 8192000000 10234380480 10673487872 1285625312 13.564 96 25 51 0 1311590304 19552880 6412112
21:05:07 39035 8192000000 10251323600 10678226944 1328066880 13.950 100 36 72 0 1361143792 24349568 8727344
21:06:56 105673 8192000000 10262928288 10690781184 1321213168 13.888 99 16 32 0 1352960768 27685728 4061872
21:10:03 38046 8192000000 10262818752 10707021824 1355944048 14.201 100 13 26 0 1374591952 15762560 2885344
21:11:43 56266 8192000000 10265129520 10716827648 1362373536 14.259 100 13 27 1 1380580448 15448880 2758032
21:14:03 24172 8192000000 10268316176 10713149440 1368934640 14.318 100 27 51 1 1387589104 12845728 5808736
21:15:29 70269 8192000000 10279576416 10735628288 1363580704 14.269 99 11 22 1 1383448688 16889792 2978192
21:17:44 24191 8192000000 10283068288 10729078784 1375895120 14.380 100 34 68 2 1396651104 13123680 7632304
21:19:26 53579 8192000000 10266643888 10721886208 1367418416 14.304 99 14 23 0 1377636960 7374000 2844544
21:21:21 65686 8192000000 10282481472 10743783424 1387114336 14.480 100 19 38 0 1400619936 9073200 4432400
21:24:35 72607 8192000000 10277522576 10738499584 1369504368 14.323 99 12 24 0 1380238720 8330272 2404080
21:26:58 65209 8192000000 10301386976 10761007104 1388957360 14.497 100 13 26 1 1403987888 11819856 3210672
21:29:06 54910 8192000000 10311835264 10770194432 1405792752 14.647 100 41 82 0 1431834656 16881360 9160544
21:31:07 74102 8192000000 10321888032 10782494720 1393173424 14.534 99 18 37 0 1413683888 16476240 4034224
21:33:25 27516 8192000000 10311232000 10768285696 1396880592 14.567 99 38 76 0 1420179808 14888032 8411184
21:35:00 36304 8192000000 10317668672 10761576448 1363681776 14.270 97 35 70 1 1381888432 10840976 7365680
21:36:38 35992 8192000000 10290091152 10725818368 1337782208 14.037 96 32 63 0 1354536208 9805488 6948512
21:38:14 97273 8192000000 10282988080 10725163008 1333696512 14.001 96 34 68 0 1349459008 8482304 7280192
21:40:53 21520 8192000000 10247420608 10688274432 1303460176 13.727 95 23 46 0 1317003824 7904944 5638704
21:42:53 53769 8192000000 10270616560 10724528128 1350376704 14.151 98 14 28 0 1376337344 22772880 3187760
21:45:23 39241 8192000000 10225255376 10675724288 1303378368 13.726 97 18 34 1 1328643888 22062464 3203056
21:47:02 73831 8192000000 10259012320 10712850432 1357940720 14.219 100 19 39 0 1374680384 12374096 4365568
21:49:20 60456 8192000000 10245143776 10688258048 1271504176 13.435 93 22 44 0 1286251504 9541232 5206096
21:51:20 27838 8192000000 10233897488 10659676160 1264289696 13.369 95 37 74 0 1284095808 12134608 7671504
21:52:51 73338 8192000000 10234518272 10676322304 1259838640 13.329 95 34 69 1 1283083056 15770288 7474128
21:55:05 22458 8192000000 10266267680 10694868992 1343009648 14.085 100 49 98 0 1367050384 13521136 10519600
21:56:32 80164 8192000000 10268841728 10725617664 1349466832 14.143 100 17 30 0 1367340208 13975600 3897776
22:00:16 87291 8192000000 10286552720 10740584448 1374258112 14.365 100 22 44 0 1396327520 17342672 4726736
22:02:54 43357 8192000000 10269366160 10740404224 1373831840 14.361 100 13 27 0 1396331024 19131328 3367856
22:04:42 26271 8192000000 10275387504 10712887296 1366019216 14.291 100 35 69 0 1383769792 10257712 7492864
22:06:19 102068 8192000000 10276865312 10720268288 1358377488 14.223 99 12 24 0 1376772224 15277760 3116976
22:09:02 53979 8192000000 10285072288 10735456256 1368469744 14.313 99 30 60 0 1388254864 13082304 6702816
22:10:56 117509 8192000000 10305844608 10761592832 1394948000 14.550 100 11 22 0 1407593152 10588176 2056976
22:13:59 85170 8192000000 10330136160 10770960384 1380669744 14.423 98 28 55 1 1400067248 13043376 6354128
22:16:25 81054 8192000000 10329201488 10790346752 1378979552 14.407 98 15 30 0 1402835872 20364688 3491632
22:18:52 119305 8192000000 10305115296 10763259904 1387815664 14.486 99 27 54 1 1408052784 13967584 6269536
22:21:55 94149 8192000000 10299368848 10764480512 1395987808 14.559 100 9 18 0 1409536528 11185504 2363216
22:24:29 64489 8192000000 10295970544 10763169792 1391937712 14.523 99 10 20 0 1406893440 12492144 2463584
22:26:35 67641 8192000000 10296720048 10749865984 1374619104 14.368 98 15 31 0 1384889680 6936048 3334528
22:28:44 50060 8192000000 10274240528 10722656256 1332551216 13.990 95 28 55 0 1350556352 11780896 6224240
22:30:35 76770 8192000000 10296699808 10770022400 1401350704 14.607 100 12 24 0 1422117664 17838752 2928208
22:33:45 61106 8192000000 10301733344 10761121792 1354874560 14.191 97 13 26 0 1372416400 14999504 2542336
22:35:46 40666 8192000000 10289802096 10741420032 1373512736 14.359 99 36 72 0 1392300224 10994912 7792576
22:37:27 16844 8192000000 10282578896 10728685568 1338224464 14.041 97 42 84 0 1358318240 11098288 8995488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-12-2022 22:39:53 to 27-12-2022 22:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-12-2022 22:40:09 to 27-12-2022 22:40:11.

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          235449
MON$OLDEST_ACTIVE               237437
MON$OLDEST_SNAPSHOT             237437
MON$NEXT_TRANSACTION            237437
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                       2012096
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:766: 
:767: mscs-test-161.budzdorov.ru    Tue Dec 27 22:38:44 2022
:768:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:769: 
:770: 
:771: mscs-test-161.budzdorov.ru    Tue Dec 27 22:38:45 2022
:772:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:773: 
--- end of diff output ---

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

27.12.22 22:40:12. 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-12-27 22:40:14 No severe PSQL-related problems occured
27.12.22 22:40:16. End of report.