Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.02.2023 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15014832 kB
MemAvailable 30404740 kB
Buffers 0 kB
Cached 16650840 kB
SwapCached 3308 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-02-18 01:38:43
DTS_END                         2023-02-18 01:38:43.1020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35700
TRN_ID                          248893
ATT_ID                          711
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: 9854.34 19866 1182521
customer order: creation 180.08 325 21609
customer order: refuse 15.12 374 1814
order to supplier: creation 47.41 1268 5689
order to supplier: removal 3.73 94722 448
invoice (draft): creation 47.64 3235 5717
invoice (draft): removal 56.01 716 6721
invoice accept: apply 43.74 16318 5249
invoice accept: cancel 51.08 732 6130
customer reserve: creation 4063.25 159 487590
customer reserve: removal 5027.30 55 603276
realization accept: apply 86.30 185 10356
realization accept: cancel 95.92 111 11510
payment from customer: creation 62.31 100 7477
payment from customer: removal 6.13 94 736
payment to supplier: creation 52.21 117 6265
payment to supplier: removal 3.57 55 428
service: total inventory turnovers 4.98 1315 598
service: total monetary turnovers 6.64 26 797
service: aggregate perf. data 0.89 19438 107
service: refresh index statistics 0.03 257971 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-02-2023 01:39:29 to 18-02-2023 01:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1534
TEST_TIME 2 3716
TEST_TIME 3 3609
TEST_TIME 4 4473
TEST_TIME 5 4648
TEST_TIME 6 4528
TEST_TIME 7 4912
TEST_TIME 8 5619
TEST_TIME 9 5551
TEST_TIME 10 5494
TEST_TIME 11 5730
TEST_TIME 12 6331
TEST_TIME 13 6191
TEST_TIME 14 6189
TEST_TIME 15 6492
TEST_TIME 16 6483
TEST_TIME 17 6675
TEST_TIME 18 6615
TEST_TIME 19 7012
TEST_TIME 20 7437
TEST_TIME 21 7592
TEST_TIME 22 7798
TEST_TIME 23 8100
TEST_TIME 24 8178
TEST_TIME 25 8422
TEST_TIME 26 8997
TEST_TIME 27 8979
TEST_TIME 28 9322
TEST_TIME 29 9307
TEST_TIME 30 10655
TEST_TIME 31 10633
TEST_TIME 32 10637
TEST_TIME 33 10591
TEST_TIME 34 10602
TEST_TIME 35 10516
TEST_TIME 36 10606
TEST_TIME 37 10691
TEST_TIME 38 10699
TEST_TIME 39 10628
TEST_TIME 40 10475
TEST_TIME 41 10365
TEST_TIME 42 10201
TEST_TIME 43 10231
TEST_TIME 44 10123
TEST_TIME 45 10215
TEST_TIME 46 10038
TEST_TIME 47 10006
TEST_TIME 48 9936
TEST_TIME 49 9844
TEST_TIME 50 10079
TEST_TIME 51 10028
TEST_TIME 52 10013
TEST_TIME 53 9972
TEST_TIME 54 9883
TEST_TIME 55 9755
TEST_TIME 56 9697
TEST_TIME 57 9777
TEST_TIME 58 9678
TEST_TIME 59 9676
TEST_TIME 60 9649
TEST_TIME 61 9603
TEST_TIME 62 9765
TEST_TIME 63 9730
TEST_TIME 64 9765
TEST_TIME 65 9814
TEST_TIME 66 9945
TEST_TIME 67 9959
TEST_TIME 68 9947
TEST_TIME 69 9921
TEST_TIME 70 10003
TEST_TIME 71 10074
TEST_TIME 72 10084
TEST_TIME 73 10077
TEST_TIME 74 10129
TEST_TIME 75 10013
TEST_TIME 76 9974
TEST_TIME 77 10011
TEST_TIME 78 9958
TEST_TIME 79 9968
TEST_TIME 80 9894
TEST_TIME 81 9859
TEST_TIME 82 9837
TEST_TIME 83 9782
TEST_TIME 84 9753
TEST_TIME 85 9735
TEST_TIME 86 9700
TEST_TIME 87 9650
TEST_TIME 88 9736
TEST_TIME 89 9788
TEST_TIME 90 9837
TEST_TIME 91 9807
TEST_TIME 92 9818
TEST_TIME 93 9873
TEST_TIME 94 9936
TEST_TIME 95 9891
TEST_TIME 96 9932
TEST_TIME 97 9942
TEST_TIME 98 9958
TEST_TIME 99 10011
TEST_TIME 100 10002
TEST_TIME 101 10010
TEST_TIME 102 9952
TEST_TIME 103 9875
TEST_TIME 104 9869
TEST_TIME 105 9845
TEST_TIME 106 9844
TEST_TIME 107 9812
TEST_TIME 108 9781
TEST_TIME 109 9752
TEST_TIME 110 9701
TEST_TIME 111 9715
TEST_TIME 112 9720
TEST_TIME 113 9703
TEST_TIME 114 9714
TEST_TIME 115 9741
TEST_TIME 116 9703
TEST_TIME 117 9776
TEST_TIME 118 9852
TEST_TIME 119 9881
TEST_TIME 120 9856

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-02-2023 01:39:33 to 18-02-2023 01:39:36.

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 1632909 1632909 0 0.00 0 18363 5 0 0
doc_list_biud 1632907 1632907 0 0.00 0 48170 26 0 0
sp_add_invoice_to_stock 5358 5249 109 2.03 45 156777 16319 0 109
sp_cancel_adding_invoice 6130 6130 0 0.00 4 56323 732 0 0
sp_cancel_client_order 1814 1814 0 0.00 4 14771 374 0 0
sp_cancel_customer_reserve 603276 603276 0 0.00 0 48172 55 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 3 19636 94 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 4248 55 0 0
sp_cancel_supplier_invoice 6728 6721 7 0.10 1 18861 716 0 7
sp_cancel_supplier_order 448 448 0 0.00 5 1086816 94722 0 0
sp_cancel_write_off 11510 11510 0 0.00 1 9007 111 0 0
sp_client_order 21609 21609 0 0.00 3 28648 325 0 0
sp_customer_reserve 487590 487590 0 0.00 0 67077 160 0 0
sp_fill_shopping_cart 33009 33009 0 0.00 1 12709 37 0 0
sp_fill_shopping_cart_clo_res 487588 487588 0 0.00 0 11716 12 0 0
sp_get_clo_for_invoice 5247 5247 0 0.00 0 38769 262 0 0
sp_kill_cost_storno 18804 18804 0 0.00 0 16771 53 0 0
sp_kill_qstorno_ret_qs2qd 1225231 1225231 0 0.00 0 48167 30 0 0
sp_kill_qty_storno 645502 645502 0 0.00 0 48170 65 0 0
sp_lock_selected_doc 21582 21582 0 0.00 0 21701 6 0 0
sp_make_cost_storno 29345 29345 0 0.00 0 14146 49 0 0
sp_multiply_rows_for_pdistr 29345 29345 0 0.00 0 7817 24 0 0
sp_multiply_rows_for_qdistr 21609 21609 0 0.00 0 24747 142 0 0
sp_pay_from_customer 7477 7477 0 0.00 3 23320 100 0 0
sp_pay_to_supplier 6265 6265 0 0.00 5 11758 118 0 0
sp_payment_common 13741 13741 0 0.00 1 23310 26 0 0
sp_qd_handle_on_cancel_clo 1814 1814 0 0.00 0 14763 351 0 0
sp_qd_handle_on_invoice_upd_sts 11377 11377 0 0.00 0 26047 332 0 0
sp_qd_handle_on_reserve_upd_sts 10356 10356 0 0.00 0 14980 70 0 0
sp_reserve_write_off 10356 10356 0 0.00 6 15225 185 0 0
sp_supplier_invoice 5717 5717 0 0.00 5 95258 3235 0 0
sp_supplier_order 5689 5689 0 0.00 4 51998 1269 0 0
srv_aggregate_perf_data 107 107 0 0.00 3042 168041 19439 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1055 135416 43346 0 0
srv_find_qd_qs_mism 1131756 1131756 0 0.00 0 18362 4 0 0
srv_make_invnt_saldo 598 598 0 0.00 28 86488 1315 0 0
srv_make_money_saldo 797 797 0 0.00 1 919 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 113837 525588 257971 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5249 5249 0 0.00 0 13293 160 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 5897 84 0 0
t$perf-norm:sp_cancel_client_order 1814 1814 0 0.00 0 6524 19 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 1652 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 867 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 24221 69 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 2034 14 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 33711 1504 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 5809 19 0 0
t$perf-norm:sp_client_order 21609 21609 0 0.00 0 8629 17 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 3460 9 0 0
t$perf-norm:sp_pay_from_customer 7477 7477 0 0.00 0 22715 15 0 0
t$perf-norm:sp_pay_to_supplier 6265 6265 0 0.00 0 24246 12 0 0
t$perf-norm:sp_reserve_write_off 10356 10356 0 0.00 0 24241 14 0 0
t$perf-norm:sp_supplier_invoice 5717 5717 0 0.00 0 3701 17 0 0
t$perf-norm:sp_supplier_order 5689 5689 0 0.00 0 5491 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 221 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 499 15 0 0
t$perf-norm:srv_make_invnt_saldo 598 598 0 0.00 0 1116 18 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 1432 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10712 10494 218 2.03 0 8050 19 0 218
v_all_customers 39700 39700 0 0.00 0 94 1 0 0
v_all_suppliers 31928 31928 0 0.00 0 21 1 0 0
v_all_wares 43218 43218 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 368 6 0 0
v_cancel_client_order 3628 3628 0 0.00 0 752 3 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 19472 20 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 440 5 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 395 3 0 0
v_cancel_supplier_invoice 832 818 14 1.68 0 4195 22 0 14
v_cancel_supplier_order 896 896 0 0.00 0 2950 11 0 0
v_cancel_write_off 2038 2038 0 0.00 0 8998 19 0 0
v_max_id_clo_ord 5689 5689 0 0.00 0 619 1 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 4257 22 0 0
v_max_id_ord_sup 5711 5711 0 0.00 0 6618 5 0 0
v_max_non_paid_invoice 1701 1701 0 0.00 0 286 2 0 0
v_max_non_paid_realizn 3878 3878 0 0.00 0 4978 6 0 0
v_min_id_clo_ord 5689 5689 0 0.00 0 1439 4 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 2817 7 0 0
v_min_id_ord_sup 5711 5711 0 0.00 0 4409 6 0 0
v_min_non_paid_invoice 6265 6265 0 0.00 0 8724 20 0 0
v_min_non_paid_realizn 7476 7476 0 0.00 0 11593 14 0 0
v_our_firm 10714 10714 0 0.00 0 17 1 0 0
v_reserve_write_off 20712 20712 0 0.00 0 8680 8 0 0
x_lock_depdocs_on_canc_invoice 12442 12442 0 0.00 0 19575 116 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 13279 331 0 0
x_make_qstorno_1000_1200 5689 5689 0 0.00 1 51336 1197 0 0
x_make_qstorno_1200_2000 5717 5717 0 0.00 2 83948 3095 0 0
x_make_qstorno_2100_3300 487590 487590 0 0.00 0 67075 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-02-2023 01:39:37 to 18-02-2023 01:39:41.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:46 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:47 39452 8192000000 9911563120 10223656960 986404064 10.747 100 89 178 0 1016370192 6508576 23457552
23:11:31 16287 8192000000 9962853184 10288156672 1043523280 11.299 100 86 172 0 1073878032 8812528 21542224
23:12:52 11413 8192000000 9984275104 10318225408 1070165456 11.554 100 87 174 1 1102245120 10663472 21416192
23:14:06 10111 8192000000 10034032192 10378469376 1117269712 12.001 100 84 169 0 1149480128 12180448 20029968
23:15:19 4497 8192000000 10074100256 10425659392 1156976160 12.375 100 85 170 0 1190172336 13040416 20155760
23:16:27 3322 8192000000 10111822464 10477428736 1203250896 12.807 100 83 167 0 1235505920 13218288 19036736
23:17:32 2005 8192000000 10159050736 10532962304 1245899104 13.201 100 88 176 0 1278213552 13004112 19310336
23:18:36 1064 8192000000 10186890912 10563493888 1270593632 13.427 100 87 167 2 1301753840 13006240 18153968
23:19:46 866 8192000000 10215126816 10600407040 1299154160 13.688 100 92 183 0 1332951440 13504080 20293200
23:20:48 9122 8192000000 10205552176 10613100544 1305483584 13.745 100 43 87 1 1327180112 12219520 9477008
23:21:57 6191 8192000000 10208100192 10617982976 1314136848 13.824 100 26 52 0 1327635152 7534336 5963968
23:23:04 6603 8192000000 10206333088 10624401408 1320823168 13.884 100 21 42 1 1331335648 5793040 4719440
23:24:14 11380 8192000000 10208486656 10628685824 1324231536 13.915 100 16 33 0 1333282256 5529744 3520976
23:25:28 31565 8192000000 10227221232 10643595264 1330064256 13.968 100 36 69 0 1343445280 5253600 8127424
23:27:01 6416 8192000000 10227528688 10642952192 1320766800 13.884 99 24 47 0 1332665856 5934288 5964768
23:28:08 9836 8192000000 10219089840 10636681216 1316942720 13.849 99 26 52 0 1328873328 6090496 5840112
23:29:22 38373 8192000000 10167950400 10580774912 1175628608 12.549 89 21 42 0 1185468800 4947568 4892624
23:31:01 11993 8192000000 10129476592 10534944768 1203854144 12.812 95 15 30 0 1212477776 5055168 3568464
23:32:14 7681 8192000000 10126657872 10521231360 1189845824 12.682 95 24 48 0 1200809968 5227376 5736768
23:33:21 9211 8192000000 10154330352 10556178432 1236941744 13.118 98 24 48 0 1251471936 9028512 5501680
23:34:31 10327 8192000000 10179877344 10581434368 1277908624 13.494 100 19 38 2 1291952960 10011008 4033328
23:35:43 15152 8192000000 10178236480 10586755072 1285618064 13.564 100 34 69 0 1300814112 7561424 7634624
23:36:58 6524 8192000000 10188482448 10601590784 1296748224 13.666 100 25 50 0 1308488896 6198560 5542112
23:38:05 10090 8192000000 10206731952 10622939136 1315539552 13.836 100 22 44 0 1325642352 5294144 4808656
23:39:24 30717 8192000000 10246507856 10656477184 1333527760 13.999 100 38 73 1 1347733248 6553792 7651696
23:40:59 63123 8192000000 10264525296 10679144448 1355660432 14.198 100 53 106 2 1380894336 12835168 12398736
23:43:07 38699 8192000000 10270879168 10693419008 1352900288 14.174 99 32 62 1 1372143168 12468656 6774224
23:44:51 26494 8192000000 10295143072 10715885568 1375174016 14.373 100 40 78 0 1401678256 17872128 8632112
23:46:22 5831 8192000000 10301398912 10726887424 1384640176 14.458 100 58 114 0 1420749408 21972752 14136480
23:47:33 7779 8192000000 10312492000 10736328704 1393152880 14.534 100 59 114 2 1427837184 21308848 13375456
23:48:55 8055 8192000000 10332523152 10749407232 1393680880 14.539 100 68 136 0 1440584032 30086928 16816224
23:50:10 3302 8192000000 10336266800 10758287360 1398321024 14.580 100 57 114 0 1444815824 31847952 14646848
23:51:17 7051 8192000000 10322982992 10744557568 1394217184 14.543 100 59 116 0 1434459040 25911072 14330784
23:52:33 9106 8192000000 10350767456 10771034112 1409459232 14.679 100 60 120 1 1455465104 31505520 14500352
23:53:48 2091 8192000000 10343799920 10759303168 1400002064 14.595 100 71 141 4 1447186048 30050480 17133504
23:54:53 3189 8192000000 10350340144 10758897664 1398289136 14.580 100 83 167 2 1455718320 37585040 19844144
23:56:01 6273 8192000000 10359074720 10777255936 1409828912 14.682 100 63 126 0 1470509328 45236784 15443632
23:57:16 2104 8192000000 10377218544 10792198144 1411289936 14.695 100 67 135 0 1480345952 51267408 17788608
23:58:29 3473 8192000000 10347797616 10759323648 1376224624 14.383 99 63 123 0 1441696112 49172848 16298640
23:59:37 10417 8192000000 10326061120 10743492608 1352036496 14.166 99 47 91 0 1422563088 57449008 13077584
00:00:58 1053 8192000000 10356262976 10760019968 1376792656 14.388 100 89 177 0 1453915072 54743536 22378880
00:02:02 5749 8192000000 10332646592 10750132224 1368367296 14.312 100 48 96 1 1440005328 58461216 13176816
00:03:11 21414 8192000000 10347533360 10771460096 1381617280 14.431 100 36 72 1 1452734896 60708640 10408976
00:04:39 42912 8192000000 10306532880 10731700224 1351302880 14.159 98 37 71 0 1406019152 45940720 8775552
00:06:30 11681 8192000000 10318522624 10767736832 1371840800 14.344 100 21 42 0 1425485504 47539552 6105152
00:07:44 45162 8192000000 10293700768 10746150912 1358544800 14.224 100 14 28 1 1406324960 44132512 3647648
00:09:33 41401 8192000000 10289003552 10712961024 1315122432 13.833 95 37 69 0 1329995360 7569280 7303648
00:11:32 108621 8192000000 10272169856 10716291072 1338607424 14.045 98 5 10 0 1351932704 12538000 787280
00:14:21 73434 8192000000 10286903776 10735955968 1348685696 14.136 98 8 16 0 1364690432 14367632 1637104
00:16:37 54261 8192000000 10316263376 10748674048 1388332000 14.491 100 38 76 0 1406906992 9385536 9189456
00:18:33 130872 8192000000 10303661008 10754224128 1396659120 14.565 100 20 38 0 1408540992 7651184 4230688
00:21:46 76617 8192000000 10314744624 10759987200 1386818768 14.477 99 24 48 0 1400970352 8714112 5437472
00:24:04 35412 8192000000 10312351600 10765914112 1404035936 14.631 100 25 49 0 1418921312 9453376 5432000
00:25:41 28004 8192000000 10314425792 10767142912 1407922416 14.665 100 34 68 0 1427277216 11646304 7708496
00:27:11 20109 8192000000 10312268848 10761048064 1389934608 14.505 99 33 64 0 1411065424 12583712 8547104
00:28:33 41579 8192000000 10300657024 10763509760 1403496016 14.626 100 31 62 0 1423169040 12666576 7006448
00:30:23 35083 8192000000 10296257136 10763780096 1391857888 14.522 99 3 6 0 1399840960 7484576 498496
00:32:02 60594 8192000000 10307329680 10767450112 1388653408 14.494 99 12 24 0 1398268720 6827440 2787872
00:34:10 75456 8192000000 10304424032 10772791296 1393135072 14.534 99 24 48 0 1415554720 16384992 6034656
00:36:32 10098 8192000000 10302629648 10766303232 1403091360 14.623 100 25 51 0 1421829664 11705520 7032784
00:37:43 67765 8192000000 10294056288 10770145280 1366916576 14.299 97 15 30 0 1383241824 12720688 3604560
00:40:10 38024 8192000000 10272989024 10742194176 1313203136 13.815 94 10 20 1 1336371904 21247792 1920976
00:41:57 43210 8192000000 10288463760 10730553344 1325021088 13.922 96 22 44 1 1341687392 11902320 4763984
00:43:45 34758 8192000000 10277778656 10725003264 1324865776 13.921 96 34 66 0 1343735552 11885200 6984576
00:45:20 72670 8192000000 10258823808 10709278720 1310730768 13.793 97 6 12 0 1321672704 9652368 1289568
00:47:33 55094 8192000000 10253508304 10705010688 1324768816 13.920 98 29 58 0 1343339072 12209024 6361232
00:49:30 104585 8192000000 10262650624 10711183360 1283262880 13.543 94 16 32 0 1302697552 15974752 3459920
00:52:19 77347 8192000000 10245540032 10694713344 1309227168 13.779 96 15 30 0 1328561472 16113536 3220768
00:55:09 55948 8192000000 10272424576 10732187648 1359609696 14.234 100 25 50 0 1386286256 21148784 5527776
00:57:24 56719 8192000000 10225157888 10683150336 1296977600 13.668 96 10 20 0 1306840544 7898032 1964912
01:00:19 38255 8192000000 10257218192 10712420352 1345585248 14.108 99 17 35 0 1357677424 7868960 4223216
01:02:02 51874 8192000000 10255837344 10723508224 1364515648 14.278 100 12 24 0 1376106080 8740336 2850096
01:04:45 58197 8192000000 10287565824 10746855424 1380516992 14.421 100 21 42 0 1405398848 20206816 4675040
01:06:44 31929 8192000000 10288862128 10747179008 1383256464 14.446 100 12 24 1 1401613440 15575040 2781936
01:08:33 41625 8192000000 10309723024 10761711616 1397496320 14.573 100 25 48 0 1412755584 9788672 5470592
01:10:15 43220 8192000000 10298382672 10754293760 1373729936 14.360 98 23 46 0 1391280800 12311728 5239136
01:12:00 47132 8192000000 10305284096 10763079680 1379273248 14.410 99 17 36 0 1400998224 17980048 3744928
01:13:48 18044 8192000000 10313272128 10757345280 1392498256 14.528 100 37 72 3 1409122640 8547824 8076560
01:15:06 37691 8192000000 10300515712 10749001728 1350306096 14.150 97 23 46 1 1365272000 10171520 4794384
01:16:44 135416 8192000000 10294781792 10751123456 1336734272 14.028 96 6 12 0 1351758048 13605616 1418160
01:20:46 88476 8192000000 10265413952 10729242624 1353305744 14.177 97 22 44 0 1365504640 7299280 4899616
01:23:16 11451 8192000000 10275100240 10726084608 1349381632 14.142 98 24 48 2 1364175040 9748784 5044624
01:24:33 21866 8192000000 10260168208 10716381184 1352043440 14.166 99 14 28 0 1361338240 6624288 2670512
01:25:59 52316 8192000000 10230971984 10689400832 1299049872 13.687 96 10 21 0 1310700992 9185520 2465600
01:27:59 69534 8192000000 10246747232 10708725760 1351960016 14.165 100 14 28 0 1367519040 12639808 2919216
01:30:16 56830 8192000000 10249536416 10700124160 1292130880 13.624 95 15 28 0 1302360256 6819792 3409584
01:32:13 67849 8192000000 10259063024 10720296960 1307256304 13.761 96 23 46 0 1327773968 15156608 5361056
01:34:25 85430 8192000000 10278962096 10732982272 1324635264 13.919 97 22 45 0 1348956016 18902240 5418512
01:36:53 106939 8192000000 10256014720 10694074368 1221155984 12.972 90 23 46 0 1236017248 9997280 4863984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-02-2023 01:39:43 to 18-02-2023 01:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-02-2023 01:40:00 to 18-02-2023 01:40:02.

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          247714
MON$OLDEST_ACTIVE               249009
MON$OLDEST_SNAPSHOT             249009
MON$NEXT_TRANSACTION            249009
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                       2057696
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:165: 
:166: mscs-test-161.budzdorov.ru    Sat Feb 18 01:38:52 2023
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:168: 
--- end of diff output ---

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

18.02.23 01:40:03. 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-02-18 01:40:05 No severe PSQL-related problems occured
18.02.23 01:40:07. End of report.