Skip to content
This repository has been archived by the owner on Dec 1, 2022. It is now read-only.

No schema found #1132

Closed
Robson96 opened this issue Jun 13, 2021 · 10 comments
Closed

No schema found #1132

Robson96 opened this issue Jun 13, 2021 · 10 comments
Labels
question Type: question about the product

Comments

@Robson96
Copy link

Robson96 commented Jun 13, 2021

Describe the bug (must be provided)
No schema found

Your Environments (must be provided)

  • OS: Linux linux-MacBook 5.4.0-73-generic #82-Ubuntu SMP Wed Apr 14 17:39:42 UTC 2021 x86_64 x86_64 x86_64 GNU/Linux
  • Compliler: g++ (Ubuntu 9.3.0-17ubuntu1~20.04) 9.3.0 Copyright (C) 2019 Free Software Foundation, Inc. This is free software; see the source for copying conditions. There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. n or clang version 10.0.0-4ubuntu1 Target: x86_64-pc-linux-gnu Thread model: posix InstalledDir: /usr/bin
  • CPU: Arquitetura: x86_64 Modo(s) operacional da CPU: 32-bit, 64-bit Ordem dos bytes: Little Endian Address sizes: 36 bits physical, 48 bits virtual CPU(s): 2 Lista de CPU(s) on-line: 0,1 Thread(s) per núcleo: 1 Núcleo(s) por soquete: 2 Soquete(s): 1 Nó(s) de NUMA: 1 ID de fornecedor: GenuineIntel Família da CPU: 6 Modelo: 15 Nome do modelo: Intel(R) Core(TM)2 Duo CPU T7500 @ 2.20GHz Step: 11 CPU MHz: 1014.089 CPU MHz máx.: 2200,0000 CPU MHz mín.: 800,0000 BogoMIPS: 4388.79 Virtualização: VT-x cache de L1d: 64 KiB cache de L1i: 64 KiB cache de L2: 4 MiB CPU(s) de nó0 NUMA: 0,1 Vulnerability Itlb multihit: KVM: Mitigation: Split huge pages Vulnerability L1tf: Mitigation; PTE Inversion; VMX EPT disabled Vulnerability Mds: Vulnerable: Clear CPU buffers attempted, no microcode; SMT disabled Vulnerability Meltdown: Mitigation; PTI Vulnerability Spec store bypass: Vulnerable Vulnerability Spectre v1: Mitigation; usercopy/swapgs barriers and __user pointer sanitization Vulnerability Spectre v2: Mitigation; Full generic retpoline, STIBP disabled, RSB filling Vulnerability Srbds: Not affected Vulnerability Tsx async abort: Not affected Opções: fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ht tm pbe syscall nx lm constant_tsc arch_perfmon pebs bts rep_good nopl cpuid aperfmperf pni dtes64 monitor ds_cpl vmx est tm2 ssse3 cx16 xtp r pdcm lahf_lm pti tpr_shadow vnmi flexpriority dtherm
  • Commit id (e.g. a3ffc7d8)

How To Reproduce(must be provided)

Steps to reproduce the behavior:

(admin@nebula) [test]> create tag usuario(nome string);
[ERROR (-8)]: Existed!

Sun, 13 Jun 2021 09:47:00 -03

(admin@nebula) [test]> insert vertex usuario (nome) values "Bob":("Bob");
[ERROR (-12)]: SemanticError: No schema found for `usuario'

Sun, 13 Jun 2021 09:47:03 -03

(admin@nebula) [test]>

Expected behavior

after you have restarted and the errors below!

(admin@nebula) [test]> insert vertex usuario (nome) values "Bob":("Bob");
Execution succeeded (time spent 103936/56524 us)

Sun, 13 Jun 2021 10:01:20 -03

(admin@nebula) [test]>
Additional context

linux@linux-MacBook:/Downloads$ sudo /usr/local/nebula/scripts/nebula.service restart all
[sudo] senha para linux:
[WARN] The maximum files allowed to open might be too few: 1024
[INFO] Stopping nebula-metad...
[INFO] Done
[INFO] Starting nebula-metad...
[INFO] Done
[INFO] Stopping nebula-graphd...
[INFO] Done
[INFO] Starting nebula-graphd...
[INFO] Done
[INFO] Stopping nebula-storaged...
[INFO] Done
[INFO] Starting nebula-storaged...
[INFO] Done
linux@linux-MacBook:
/Downloads$ ./nebula-console-v2.0.0-ga -addr=127.0.0.1 -port=9669 -u admin -p admin
2021/06/13 10:00:01 [INFO] connection pool is initialized successfully

Welcome to Nebula Graph!

(admin@nebula) [(none)]> use test;
Execution succeeded (time spent 760237/733622 us)

Sun, 13 Jun 2021 10:00:06 -03

(admin@nebula) [test]> insert vertex usuario (nome) values "Bob":("Bob");
[ERROR (-8)]: Storage Error: part: 81, error: E_RPC_FAILURE(-3).

Sun, 13 Jun 2021 10:00:08 -03

(admin@nebula) [test]> insert vertex usuario (nome) values "Bob":("Bob");
[ERROR (-8)]: Storage Error: part: 81, error: E_RPC_FAILURE(-3).

Sun, 13 Jun 2021 10:00:15 -03

(admin@nebula) [test]>

@wey-gu
Copy link

wey-gu commented Jun 15, 2021

Dear @Robson96 ,

Kindly note that inserting data immediately may fail after creating it:

ref: https://docs.nebula-graph.io/2.0.1/3.ngql-guide/10.tag-statements/1.create-tag/#implementation_of_the_operation and I quoted as below:

Trying to insert vertices with a newly created tag may fail, because the creation of the tag is implemented asynchronously.

Nebula Graph implements the creation in the next heartbeat cycle. To make sure the creation is successful, take one of the following approaches:

Find the new tag in the result of SHOW TAGS. If you can't, wait a few seconds and try again.
Wait for two heartbeat cycles, i.e., 20 seconds.
To change the heartbeat interval, modify the heartbeat_interval_secs parameter in the configuration files for all services.

For E_RPC_FAILURE(-3) it was when a storage client calling from a query engine encountering RPC failure, could you check storaged and graphd logs?

Also, I see [WARN] The maximum files allowed to open might be too few: 1024 in your print out, it may be reaching the limit of open files that caused the issue, please refer to: https://docs.nebula-graph.io/2.0.1/5.configurations-and-logs/1.configurations/6.kernel-config/#ulimit_-n

@HarrisChu HarrisChu added the question Type: question about the product label Jun 15, 2021
@Robson96
Copy link
Author

@wey-gu which would they be?

linux@linux-MacBook:/usr/local/nebula/logs$ ls
graphd-stderr.log
graphd-stdout.log
metad-stderr.log
metad-stdout.log
nebula-graphd.ERROR
nebula-graphd.INFO
nebula-graphd.linux-MacBook.root.log.ERROR.20210317-184612.2482
nebula-graphd.linux-MacBook.root.log.ERROR.20210317-193406.1970
nebula-graphd.linux-MacBook.root.log.ERROR.20210317-214237.1971
nebula-graphd.linux-MacBook.root.log.ERROR.20210317-220031.2366
nebula-graphd.linux-MacBook.root.log.ERROR.20210512-125054.5796
nebula-graphd.linux-MacBook.root.log.ERROR.20210512-212832.5261
nebula-graphd.linux-MacBook.root.log.ERROR.20210609-155135.6423
nebula-graphd.linux-MacBook.root.log.ERROR.20210613-100008.5520
nebula-graphd.linux-MacBook.root.log.ERROR.20210613-105131.4778
nebula-graphd.linux-MacBook.root.log.ERROR.20210614-115626.5482
nebula-graphd.linux-MacBook.root.log.INFO.20210317-184255.2482
nebula-graphd.linux-MacBook.root.log.INFO.20210317-192318.1970
nebula-graphd.linux-MacBook.root.log.INFO.20210317-214152.1971
nebula-graphd.linux-MacBook.root.log.INFO.20210317-215641.2366
nebula-graphd.linux-MacBook.root.log.INFO.20210512-083428.5796
nebula-graphd.linux-MacBook.root.log.INFO.20210512-204032.5261
nebula-graphd.linux-MacBook.root.log.INFO.20210524-121003.9785
nebula-graphd.linux-MacBook.root.log.INFO.20210526-094922.5610
nebula-graphd.linux-MacBook.root.log.INFO.20210526-104458.8331
nebula-graphd.linux-MacBook.root.log.INFO.20210609-155034.6423
nebula-graphd.linux-MacBook.root.log.INFO.20210613-095949.5520
nebula-graphd.linux-MacBook.root.log.INFO.20210613-104912.4778
nebula-graphd.linux-MacBook.root.log.INFO.20210614-115338.5482
nebula-graphd.linux-MacBook.root.log.WARNING.20210317-184612.2482
nebula-graphd.linux-MacBook.root.log.WARNING.20210317-193406.1970
nebula-graphd.linux-MacBook.root.log.WARNING.20210317-214237.1971
nebula-graphd.linux-MacBook.root.log.WARNING.20210317-220031.2366
nebula-graphd.linux-MacBook.root.log.WARNING.20210512-125054.5796
nebula-graphd.linux-MacBook.root.log.WARNING.20210512-212832.5261
nebula-graphd.linux-MacBook.root.log.WARNING.20210609-155135.6423
nebula-graphd.linux-MacBook.root.log.WARNING.20210613-100008.5520
nebula-graphd.linux-MacBook.root.log.WARNING.20210613-105131.4778
nebula-graphd.linux-MacBook.root.log.WARNING.20210614-115626.5482
nebula-graphd.WARNING
nebula-metad.ERROR
nebula-metad.INFO
nebula-metad.linux-MacBook.root.log.ERROR.20210317-215641.1878
nebula-metad.linux-MacBook.root.log.ERROR.20210317-224129.2265
nebula-metad.linux-MacBook.root.log.ERROR.20210512-083428.5735
nebula-metad.linux-MacBook.root.log.ERROR.20210512-233400.5160
nebula-metad.linux-MacBook.root.log.ERROR.20210524-120955.1877
nebula-metad.linux-MacBook.root.log.ERROR.20210526-094921.5517
nebula-metad.linux-MacBook.root.log.ERROR.20210526-160152.8152
nebula-metad.linux-MacBook.root.log.ERROR.20210609-161054.6320
nebula-metad.linux-MacBook.root.log.ERROR.20210613-094659.2389
nebula-metad.linux-MacBook.root.log.ERROR.20210613-104911.4685
nebula-metad.linux-MacBook.root.log.ERROR.20210614-115338.5389
nebula-metad.linux-MacBook.root.log.INFO.20210317-184252.2389
nebula-metad.linux-MacBook.root.log.INFO.20210317-192316.1877
nebula-metad.linux-MacBook.root.log.INFO.20210317-214149.1878
nebula-metad.linux-MacBook.root.log.INFO.20210317-215641.2265
nebula-metad.linux-MacBook.root.log.INFO.20210512-083428.5735
nebula-metad.linux-MacBook.root.log.INFO.20210512-204029.5160
nebula-metad.linux-MacBook.root.log.INFO.20210524-120959.9553
nebula-metad.linux-MacBook.root.log.INFO.20210526-094920.5517
nebula-metad.linux-MacBook.root.log.INFO.20210526-104456.8152
nebula-metad.linux-MacBook.root.log.INFO.20210609-155031.6320
nebula-metad.linux-MacBook.root.log.INFO.20210613-095933.5133
nebula-metad.linux-MacBook.root.log.INFO.20210613-104908.4685
nebula-metad.linux-MacBook.root.log.INFO.20210614-115335.5389
nebula-metad.linux-MacBook.root.log.WARNING.20210317-215641.1878
nebula-metad.linux-MacBook.root.log.WARNING.20210317-224129.2265
nebula-metad.linux-MacBook.root.log.WARNING.20210512-083428.5735
nebula-metad.linux-MacBook.root.log.WARNING.20210512-233400.5160
nebula-metad.linux-MacBook.root.log.WARNING.20210524-120955.1877
nebula-metad.linux-MacBook.root.log.WARNING.20210526-094921.5517
nebula-metad.linux-MacBook.root.log.WARNING.20210526-160152.8152
nebula-metad.linux-MacBook.root.log.WARNING.20210609-161054.6320
nebula-metad.linux-MacBook.root.log.WARNING.20210613-094659.2389
nebula-metad.linux-MacBook.root.log.WARNING.20210613-104911.4685
nebula-metad.linux-MacBook.root.log.WARNING.20210614-115338.5389
nebula-metad.WARNING
nebula-storaged.ERROR
nebula-storaged.INFO
nebula-storaged.linux-MacBook.root.log.ERROR.20210512-083430.5824
nebula-storaged.linux-MacBook.root.log.ERROR.20210526-094926.5667
nebula-storaged.linux-MacBook.root.log.ERROR.20210526-160154.8453
nebula-storaged.linux-MacBook.root.log.ERROR.20210613-100658.5772
nebula-storaged.linux-MacBook.root.log.ERROR.20210613-104916.4832
nebula-storaged.linux-MacBook.root.log.ERROR.20210614-115342.5537
nebula-storaged.linux-MacBook.root.log.INFO.20210317-184257.2536
nebula-storaged.linux-MacBook.root.log.INFO.20210317-192321.2024
nebula-storaged.linux-MacBook.root.log.INFO.20210317-214154.2025
nebula-storaged.linux-MacBook.root.log.INFO.20210317-215642.2444
nebula-storaged.linux-MacBook.root.log.INFO.20210512-083428.5824
nebula-storaged.linux-MacBook.root.log.INFO.20210512-204034.5307
nebula-storaged.linux-MacBook.root.log.INFO.20210524-121011.10010
nebula-storaged.linux-MacBook.root.log.INFO.20210526-094925.5667
nebula-storaged.linux-MacBook.root.log.INFO.20210526-104502.8453
nebula-storaged.linux-MacBook.root.log.INFO.20210609-155037.6487
nebula-storaged.linux-MacBook.root.log.INFO.20210613-095954.5772
nebula-storaged.linux-MacBook.root.log.INFO.20210613-104914.4832
nebula-storaged.linux-MacBook.root.log.INFO.20210614-115341.5537
nebula-storaged.linux-MacBook.root.log.WARNING.20210512-083428.5824
nebula-storaged.linux-MacBook.root.log.WARNING.20210526-094926.5667
nebula-storaged.linux-MacBook.root.log.WARNING.20210526-160154.8453
nebula-storaged.linux-MacBook.root.log.WARNING.20210613-100658.5772
nebula-storaged.linux-MacBook.root.log.WARNING.20210613-104916.4832
nebula-storaged.linux-MacBook.root.log.WARNING.20210614-115342.5537
nebula-storaged.WARNING
storaged-stderr.log
storaged-stdout.log

@wey-gu
Copy link

wey-gu commented Jun 18, 2021

Dear @Robson96 ,
For graphd:
Could you check graphd-stderr.log and nebula-graphd.ERROR ?
For those files with time period, the latest file was symbol-linked to the short ones like nebula-graphd.ERROR.
Thanks.

@Robson96
Copy link
Author

linux@linux-MacBook:/usr/local/nebula/logs$ cat graphd-stderr.log
E0512 12:50:54.735440 5917 MutateValidator.cpp:61] No schema found for person
E0512 12:50:54.788934 5917 QueryInstance.cpp:103] SemanticError: No schema found for person' E0512 12:50:54.812155 5917 QueryInstance.cpp:103] EdgeName like' is nonexistent
E0512 12:50:54.813165 5917 QueryInstance.cpp:103] SyntaxError: syntax error near \"Bob\"' E0512 12:52:15.012769 5917 QueryInstance.cpp:103] SyntaxError: syntax error near "Bob"'
E0512 12:53:25.048094 5918 QueryInstance.cpp:103] SyntaxError: syntax error near \"Bob\"' E0512 12:54:21.158677 5918 QueryInstance.cpp:103] SyntaxError: syntax error near "Bob"'
E0512 12:55:14.199504 5918 QueryInstance.cpp:103] SemanticError: like.likenes', not found the property likenes'.
E0512 15:33:33.760713 5917 QueryInstance.cpp:103] SyntaxError: syntax error near over' E0512 15:35:06.241958 5917 QueryInstance.cpp:103] SyntaxError: syntax error near over'
E0512 16:04:11.009061 5910 MetaClient.cpp:124] Heartbeat failed, status:Unknown error!
E0512 21:28:32.315935 5303 QueryInstance.cpp:103] SyntaxError: syntax error near = 2;' E0512 21:35:10.237396 5303 QueryInstance.cpp:103] SyntaxError: syntax error near _dst'
E0317 21:42:37.846276 2021 QueryInstance.cpp:103] SyntaxError: syntax error near ls' E0317 21:42:54.110023 2021 UseValidator.cpp:24] Unknown space: rede E0317 21:42:54.110219 2021 QueryInstance.cpp:103] SpaceNotFound: E0317 21:43:16.966447 2021 UseValidator.cpp:24] Unknown space: space E0317 21:43:16.966617 2021 QueryInstance.cpp:103] SpaceNotFound: E0317 21:43:21.806725 2021 UseValidator.cpp:24] Unknown space: rede E0317 21:43:21.806927 2021 QueryInstance.cpp:103] SpaceNotFound: E0317 21:43:23.366590 2021 UseValidator.cpp:24] Unknown space: rede E0317 21:43:23.366775 2021 QueryInstance.cpp:103] SpaceNotFound: E0317 21:44:04.079349 2021 UseValidator.cpp:24] Unknown space: REDE E0317 21:44:04.079571 2021 QueryInstance.cpp:103] SpaceNotFound: E0317 21:44:08.343715 2021 UseValidator.cpp:24] Unknown space: rede E0317 21:44:08.343914 2021 QueryInstance.cpp:103] SpaceNotFound: E0317 21:44:25.279742 2021 UseValidator.cpp:24] Unknown space: rede E0317 21:44:25.279945 2021 QueryInstance.cpp:103] SpaceNotFound: E0317 21:44:51.504298 2021 QueryInstance.cpp:103] SyntaxError: syntax error near help'
E0317 21:45:07.320505 2021 QueryInstance.cpp:103] SyntaxError: syntax error near HDFs' E0317 21:45:08.840664 2021 QueryInstance.cpp:103] SyntaxError: syntax error near HDFS'
E0317 21:45:39.489621 2021 UseValidator.cpp:24] Unknown space: rede
E0317 21:45:39.489848 2021 QueryInstance.cpp:103] SpaceNotFound:
E0317 21:55:32.420676 2020 UseValidator.cpp:24] Unknown space: rede
E0317 21:55:32.420892 2020 QueryInstance.cpp:103] SpaceNotFound:
E0317 21:56:09.510000 2020 UseValidator.cpp:24] Unknown space: rede1
E0317 21:56:09.510221 2020 QueryInstance.cpp:103] SpaceNotFound:
E0317 22:00:31.842818 2516 QueryInstance.cpp:103] SyntaxError: syntax error near date' E0317 22:01:20.827862 2516 QueryInstance.cpp:103] SyntaxError: syntax error near date'
E0317 22:01:29.051935 2516 QueryInstance.cpp:103] SyntaxError: syntax error near date' E0317 22:12:13.696554 2517 QueryInstance.cpp:103] SyntaxError: syntax error near $.posted'
E0317 22:12:17.528612 2517 QueryInstance.cpp:103] SyntaxError: syntax error near _dst' E0317 22:13:57.074877 2517 QueryInstance.cpp:103] SemanticError: posted.src', not found the property src'. E0317 22:14:13.834787 2517 QueryInstance.cpp:103] SemanticError: posted.nome', not found the property nome'. E0317 22:15:39.804385 2516 QueryInstance.cpp:103] SyntaxError: syntax error near _vid;'
E0317 22:15:43.436488 2516 QueryInstance.cpp:103] SyntaxError: syntax error near _id' E0317 22:15:46.404569 2516 QueryInstance.cpp:103] SemanticError: Invalid label identifiers: posted E0317 22:41:11.074440 2516 QueryInstance.cpp:103] SyntaxError: syntax error near quit'
E0317 19:34:06.264431 2020 QueryInstance.cpp:103] SemanticError: $-.post', not exist prop post'
E0317 19:34:13.048566 2020 QueryInstance.cpp:103] SemanticError: $-.person', not exist prop person'
E0317 19:34:15.744702 2020 QueryInstance.cpp:103] SemanticError: $-.person', not exist prop person'
E0524 09:46:27.847301 2019 QueryInstance.cpp:103] SemanticError: $-.user', not exist prop user'
E0524 12:09:36.952610 2019 UseValidator.cpp:24] Unknown space: imapps
E0524 12:09:37.220135 2019 QueryInstance.cpp:103] SpaceNotFound:
E0609 15:51:35.528056 6483 QueryInstance.cpp:103] SyntaxError: syntax error near descrive' E0609 15:52:05.960788 6483 QueryInstance.cpp:103] SyntaxError: syntax error near imapps'
E0609 15:52:12.464926 6483 QueryInstance.cpp:103] SyntaxError: syntax error near space' E0317 18:46:12.573369 2531 QueryInstance.cpp:103] SyntaxError: syntax error near ;'
E0317 18:51:38.510423 2531 QueryInstance.cpp:103] SyntaxError: syntax error near ;' E0317 18:52:09.895222 2531 QueryInstance.cpp:103] SemanticError: Invalid label identifiers: like E0613 09:36:24.843993 2531 QueryInstance.cpp:103] SyntaxError: syntax error near date'
E0613 09:37:31.539497 2531 MutateValidator.cpp:61] No schema found for usuario
E0613 09:37:31.539664 2531 QueryInstance.cpp:103] SemanticError: No schema found for usuario' E0613 09:38:31.593827 2531 MutateValidator.cpp:61] No schema found for usuario E0613 09:38:31.593976 2531 QueryInstance.cpp:103] SemanticError: No schema found for usuario'
E0613 09:38:53.986755 2531 MutateValidator.cpp:61] No schema found for usuario
E0613 09:38:53.986907 2531 QueryInstance.cpp:103] SemanticError: No schema found for usuario' E0613 09:39:13.978513 2531 MutateValidator.cpp:61] No schema found for usuario E0613 09:39:13.978662 2531 QueryInstance.cpp:103] SemanticError: No schema found for usuario'
E0613 09:42:21.608403 2531 QueryInstance.cpp:103] SyntaxError: syntax error near usuario' E0613 09:42:24.879271 2531 QueryInstance.cpp:103] SyntaxError: syntax error near usuario'
E0613 09:43:09.568404 2532 QueryInstance.cpp:103] SyntaxError: syntax error near space' E0613 09:43:13.216243 2532 QueryInstance.cpp:103] SyntaxError: syntax error near test'
E0613 09:43:47.496088 2532 QueryInstance.cpp:103] SyntaxError: syntax error near SPACE' E0613 09:43:51.832810 2532 QueryInstance.cpp:103] SyntaxError: syntax error near IF'
E0613 09:43:57.768965 2532 QueryInstance.cpp:103] SyntaxError: syntax error near test' E0613 09:46:41.333297 2531 MutateValidator.cpp:61] No schema found for usuario E0613 09:46:41.333519 2531 QueryInstance.cpp:103] SemanticError: No schema found for usuario'
E0613 09:47:00.101212 2532 TagExecutor.cpp:26] SpaceId: 1, Create tag usuario' failed: Existed! E0613 09:47:00.101388 2531 QueryInstance.cpp:103] Existed! E0613 09:47:03.804632 2531 MutateValidator.cpp:61] No schema found for usuario E0613 09:47:03.804767 2531 QueryInstance.cpp:103] SemanticError: No schema found for usuario'
*** Aborted at 1623589174 (unix time) try "date -d @1623589174" if you are using GNU date ***
PC: @ 0x1d4d827 folly::IOThreadPoolExecutor::getEventBase()
*** SIGSEGV (@0x50) received by PID 2482 (TID 0x7f3bb437d700) from PID 80; stack trace: ***
@ 0x1e66581 (unknown)
@ 0x7f3bbc38c41f (unknown)
@ 0x1d4d827 folly::IOThreadPoolExecutor::getEventBase()
@ 0x15e1150 _ZN6nebula4meta10MetaClient11getResponseINS0_4cpp25HBReqEZNS1_9heartbeatEvEUlT_T0_E_ZNS1_9heartbeatEvEUlONS3_6HBRespEE0_S8_bEEvS5_S6_T1_N5folly7PromiseINS_8StatusOrIT3_EEEEbii.constprop.0
@ 0x15e1c17 nebula::meta::MetaClient::heartbeat()
@ 0x1626797 nebula::meta::MetaClient::heartBeatThreadFunc()
@ 0x16b10de _ZNSt17_Function_handlerIFvvESt5_BindIFZN6nebula6thread13GenericWorker12addDelayTaskIMNS2_4meta10MetaClientEFvvEJPS7_EEENSt9enable_ifIXsrSt7is_voidINSt9result_ofIFT_DpT0_EE4typeEE5valueEN5folly10SemiFutureINSL_4UnitEEEE4typeEmOSE_DpOSF_EUlvE_vEEE9_M_invokeERKSt9_Any_data
@ 0x1a2728a ZZN6nebula6thread13GenericWorker8onNotifyEvENUlisPvE_4_FUNEisS2
@ 0x1e89de2 (unknown)
@ 0x1e8a4c6 event_base_loop
@ 0x1a27860 _ZNSt6thread11_State_implINS_8_InvokerISt5tupleIJPFvRKNSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEERKSt8functionIFvvEEES8_St5_BindIFMN6nebula6thread13GenericWorkerEFvvEPSL_EEEEEEE6_M_runEv
@ 0x229b6cf execute_native_thread_routine
@ 0x7f3bbc380608 start_thread
@ 0x7f3bbc2a7292 clone
E0613 10:00:08.923288 5564 StorageClientBase.inl:214] Request to "127.0.0.1":9779 failed: N6apache6thrift9transport19TTransportExceptionE: AsyncSocketException: connect failed, type = Socket not open, errno = 111 (Connection refused): Connection refused
E0613 10:00:08.924216 5646 StorageAccessExecutor.h:35] InsertVerticesExecutor failed, error E_RPC_FAILURE, part 81
E0613 10:00:08.924299 5646 StorageAccessExecutor.h:112] Storage Error: part: 81, error: E_RPC_FAILURE(-3).
E0613 10:00:08.924408 5646 QueryInstance.cpp:103] Storage Error: part: 81, error: E_RPC_FAILURE(-3).
E0613 10:00:15.773821 5564 StorageClientBase.inl:214] Request to "127.0.0.1":9779 failed: N6apache6thrift9transport19TTransportExceptionE: AsyncSocketException: connect failed, type = Socket not open, errno = 111 (Connection refused): Connection refused
E0613 10:00:15.774094 5645 StorageAccessExecutor.h:35] InsertVerticesExecutor failed, error E_RPC_FAILURE, part 81
E0613 10:00:15.774178 5645 StorageAccessExecutor.h:112] Storage Error: part: 81, error: E_RPC_FAILURE(-3).
E0613 10:00:15.774289 5645 QueryInstance.cpp:103] Storage Error: part: 81, error: E_RPC_FAILURE(-3).
E0613 10:06:58.419538 5646 StorageAccessExecutor.h:35] InsertVerticesExecutor failed, error E_INVALID_VID, part 89
E0613 10:06:58.454475 5646 QueryInstance.cpp:103] Storage Error: The VID must be a 64-bit interger or a string fitting space vertex id length limit.
E0613 10:07:32.502636 5646 QueryInstance.cpp:103] SyntaxError: syntax error near descrive' E0613 10:07:36.481026 5646 SpaceExecutor.cpp:40] Not existed! E0613 10:07:36.481300 5645 QueryInstance.cpp:103] Not existed! E0613 10:08:22.722560 5646 StorageAccessExecutor.h:35] InsertVerticesExecutor failed, error E_INVALID_VID, part 89 E0613 10:08:22.722813 5645 QueryInstance.cpp:103] Storage Error: The VID must be a 64-bit interger or a string fitting space vertex id length limit. E0613 10:08:24.218225 5646 StorageAccessExecutor.h:35] InsertVerticesExecutor failed, error E_INVALID_VID, part 89 E0613 10:08:24.218411 5646 QueryInstance.cpp:103] Storage Error: The VID must be a 64-bit interger or a string fitting space vertex id length limit. E0613 10:14:16.463631 5646 QueryInstance.cpp:103] SyntaxError: syntax error near over'
E0613 10:14:47.697343 5646 QueryInstance.cpp:103] SyntaxError: syntax error near ^.posted' E0613 10:51:31.826727 4827 QueryInstance.cpp:103] SyntaxError: syntax error near story'
E0613 10:52:08.531502 4828 QueryInstance.cpp:103] SyntaxError: syntax error near story' E0613 10:53:16.668756 4828 QueryInstance.cpp:103] SyntaxError: syntax error near story'
E0613 10:54:07.260311 4828 QueryInstance.cpp:103] SyntaxError: syntax error near story' E0613 10:54:30.293604 4828 QueryInstance.cpp:103] SyntaxError: syntax error near story'
E0613 11:04:48.830830 4828 QueryInstance.cpp:103] SemanticError: $$.usuario.name', not found the property name'.
E0613 11:05:03.553586 4827 QueryInstance.cpp:103] SyntaxError: syntax error near story' E0613 11:05:18.903723 4827 QueryInstance.cpp:103] SyntaxError: syntax error near story'
E0613 11:34:59.011829 4827 QueryInstance.cpp:103] SyntaxError: syntax error near macth' E0613 11:36:40.141129 4828 QueryInstance.cpp:103] SyntaxError: syntax error near macth'
E0613 11:39:01.256515 4828 QueryInstance.cpp:103] SyntaxError: syntax error near macth' E0614 11:56:26.075171 5532 QueryInstance.cpp:103] SyntaxError: syntax error near state'
E0614 11:56:30.211043 5532 QueryInstance.cpp:103] SyntaxError: syntax error near ;' E0614 12:01:30.209301 5532 QueryInstance.cpp:103] SyntaxError: syntax error near ();'
E0614 12:03:43.932960 5532 QueryInstance.cpp:103] SyntaxError: syntax error near in' E0614 12:04:00.677244 5532 QueryInstance.cpp:103] SyntaxError: syntax error near in'
E0614 13:33:18.140151 5532 TagExecutor.cpp:26] SpaceId: 1, Create tag story' failed: Existed! E0614 13:33:18.140903 5531 QueryInstance.cpp:103] Existed! E0614 13:34:21.866470 5531 QueryInstance.cpp:103] SyntaxError: syntax error near posted'

@Robson96
Copy link
Author

linux@linux-MacBook:/usr/local/nebula/logs$ cat nebula-graphd.ERROR
Log file created at: 2021/06/14 11:56:26
Running on machine: linux-MacBook
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
E0614 11:56:26.075171 5532 QueryInstance.cpp:103] SyntaxError: syntax error near state' E0614 11:56:30.211043 5532 QueryInstance.cpp:103] SyntaxError: syntax error near ;'
E0614 12:01:30.209301 5532 QueryInstance.cpp:103] SyntaxError: syntax error near ();' E0614 12:03:43.932960 5532 QueryInstance.cpp:103] SyntaxError: syntax error near in'
E0614 12:04:00.677244 5532 QueryInstance.cpp:103] SyntaxError: syntax error near in' E0614 13:33:18.140151 5532 TagExecutor.cpp:26] SpaceId: 1, Create tag story' failed: Existed!
E0614 13:33:18.140903 5531 QueryInstance.cpp:103] Existed!
E0614 13:34:21.866470 5531 QueryInstance.cpp:103] SyntaxError: syntax error near `posted'

@wey-gu
Copy link

wey-gu commented Jun 21, 2021

Dear Robson,

it looks like in 09:47:00, meta hosts are not yet synced. In this case if it's not synced in two heartbeat cycle, we could check hosts status with show hosts meta;

E0613 09:47:00.101212 2532 TagExecutor.cpp:26] SpaceId: 1, Create tag usuario' failed: Existed! E0613 09:47:00.101388 2531 QueryInstance.cpp:103] Existed! E0613 09:47:03.804632 2531 MutateValidator.cpp:61] No schema found for usuario E0613 09:47:03.804767 2531 QueryInstance.cpp:103] SemanticError: No schema found for usuario'

An example of show hosts meta;

(user@nebula) [basketballplayer]> SHOW HOSTS META;
+----------+------+----------+--------+--------------+
| Host     | Port | Status   | Role   | Git Info Sha |
+----------+------+----------+--------+--------------+
| "metad2" | 9559 | "ONLINE" | "META" | "761f22b"    |
+----------+------+----------+--------+--------------+
| "metad0" | 9559 | "ONLINE" | "META" | "761f22b"    |
+----------+------+----------+--------+--------------+
| "metad1" | 9559 | "ONLINE" | "META" | "761f22b"    |
+----------+------+----------+--------+--------------+

Then after sometime, I saw your insert scuceeded.

(admin@nebula) [test]> insert vertex usuario (nome) values "Bob":("Bob");
Execution succeeded (time spent 103936/56524 us)

Sun, 13 Jun 2021 10:01:20 -03

Also, i see you encounterred RPC errors. It should be stroaged is not yet up and ready to take requests. You could check by show hosts; I guess before you can actually insert the vertex, the storaged is still booting up. You can check storaged's log to verify this hypothesis.

(admin@nebula) [test]> insert vertex usuario (nome) values "Bob":("Bob");
[ERROR (-8)]: Storage Error: part: 81, error: E_RPC_FAILURE(-3).

Sun, 13 Jun 2021 10:00:08 -03

A example of show hosts is like:

(user@nebula) [basketballplayer]> show hosts
+-------------+------+----------+--------------+-------------------------------------------------------------+-------------------------------------------------------------+
| Host        | Port | Status   | Leader count | Leader distribution                                         | Partition distribution                                      |
+-------------+------+----------+--------------+-------------------------------------------------------------+-------------------------------------------------------------+
| "storaged0" | 9779 | "ONLINE" | 10           | "basketballplayer:3, chia:3, importer_test:1, twitter:3"    | "basketballplayer:3, chia:3, importer_test:1, twitter:3"    |
+-------------+------+----------+--------------+-------------------------------------------------------------+-------------------------------------------------------------+
| "storaged1" | 9779 | "ONLINE" | 14           | "basketballplayer:4, chia:4, importer_test:2, twitter:4"    | "basketballplayer:4, chia:4, importer_test:2, twitter:4"    |
+-------------+------+----------+--------------+-------------------------------------------------------------+-------------------------------------------------------------+
| "storaged2" | 9779 | "ONLINE" | 11           | "basketballplayer:3, chia:3, importer_test:2, twitter:3"    | "basketballplayer:3, chia:3, importer_test:2, twitter:3"    |
+-------------+------+----------+--------------+-------------------------------------------------------------+-------------------------------------------------------------+
| "Total"     |      |          | 35           | "basketballplayer:10, chia:10, importer_test:5, twitter:10" | "basketballplayer:10, chia:10, importer_test:5, twitter:10" |
+-------------+------+----------+--------------+-------------------------------------------------------------+-------------------------------------------------------------+

ref: https://docs.nebula-graph.io/2.0.1/2.quick-start/0.FAQ/#about_executions

@Robson96
Copy link
Author

Robson96 commented Jun 23, 2021

linux@linux-MacBook:/usr/local/nebula/logs$ cat nebula-metad.ERROR
Log file created at: 2021/06/14 11:53:38
Running on machine: linux-MacBook
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
E0614 11:53:38.043859 5435 FileBasedWal.cpp:440] LogId is not consistent0 51440
E0614 13:33:18.139430 5517 CreateTagProcessor.cpp:48] Create Tag Failed :story has existed
E0614 21:36:00.681645 5509 JobDescription.cpp:188] Loading Job Description Failed
E0614 21:36:00.681777 5509 JobManager.cpp:88] [JobManager] load an invalid job from queue 0
linux@linux-MacBook:/usr/local/nebula/logs$ cat nebula-metad.INFO
Log file created at: 2021/06/14 11:53:35
Running on machine: linux-MacBook
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
I0614 11:53:35.486014 5389 MetaDaemon.cpp:246] identify myself as "127.0.0.1":9559
I0614 11:53:35.614241 5389 NebulaStore.cpp:47] Start the raft service...
I0614 11:53:35.665947 5389 RaftexService.cpp:65] Init thrift server for raft service, port: 9560
I0614 11:53:35.666322 5443 RaftexService.cpp:98] Starting the Raftex Service
I0614 11:53:35.697909 5443 RaftexService.cpp:86] Starting the Raftex Service on 9560
I0614 11:53:35.697976 5443 RaftexService.cpp:110] Start the Raftex Service successfully
I0614 11:53:35.698032 5389 NebulaStore.cpp:75] Scan the local path, and init the spaces_
I0614 11:53:35.698161 5389 NebulaStore.cpp:81] Scan path "data/meta/nebula/0"
I0614 11:53:36.573976 5389 RocksEngine.cpp:119] open rocksdb on data/meta/nebula/0/data
I0614 11:53:36.574026 5389 NebulaStore.cpp:111] Load space 0 from disk
I0614 11:53:36.574088 5389 NebulaStore.cpp:146] Need to open 1 parts of space 0
E0614 11:53:38.043859 5435 FileBasedWal.cpp:440] LogId is not consistent0 51440
W0614 11:53:38.044802 5435 FileBasedWal.cpp:481] Invalid wal data/meta/nebula/0/wal/0/0000000000000000001.wal, truncate from offset 10268494
I0614 11:53:38.044903 5435 FileBasedWal.cpp:65] [Port: 9560, Space: 0, Part: 0] lastLogId in wal is 51439, lastLogTerm is 12, path is data/meta/nebula/0/wal/0/0000000000000000001.wal
I0614 11:53:38.045042 5435 RaftPart.cpp:295] [Port: 9560, Space: 0, Part: 0] There are 0 peer hosts, and total 1 copies. The quorum is 1, as learner 0, lastLogId 51439, lastLogTerm 12, committedLogId 51439, term 12
I0614 11:53:38.045336 5435 NebulaStore.cpp:177] Load part 0, 0 from disk
I0614 11:53:38.045392 5389 NebulaStore.cpp:192] Load space 0 complete
I0614 11:53:38.045425 5389 NebulaStore.cpp:201] Init data from partManager for "127.0.0.1":9559
I0614 11:53:38.045465 5389 NebulaStore.cpp:291] Data space 0 has existed!
I0614 11:53:38.045506 5389 NebulaStore.cpp:330] [Space: 0, Part: 0] has existed!
I0614 11:53:38.045565 5389 NebulaStore.cpp:68] Register handler...
I0614 11:53:38.045594 5389 MetaDaemon.cpp:99] Waiting for the leader elected...
I0614 11:53:38.045624 5389 MetaDaemon.cpp:112] Leader has not been elected, sleep 1s
I0614 11:53:38.498050 5436 RaftPart.cpp:1043] [Port: 9560, Space: 0, Part: 0] Start leader election, reason: lastMsgDur 2032, term 12
I0614 11:53:38.498237 5436 RaftPart.cpp:1193] [Port: 9560, Space: 0, Part: 0] Sending out an election request (space = 0, part = 0, term = 13, lastLogId = 51439, lastLogTerm = 12, candidateIP = 127.0.0.1, candidatePort = 9560)
I0614 11:53:38.498287 5436 RaftPart.cpp:1152] [Port: 9560, Space: 0, Part: 0] Partition is elected as the new leader for term 13
I0614 11:53:38.498338 5436 RaftPart.cpp:1248] [Port: 9560, Space: 0, Part: 0] The partition is elected as the leader
I0614 11:53:39.045820 5389 MetaDaemon.cpp:141] Get meta version is 2
I0614 11:53:39.046137 5430 MetaVersionMan.cpp:66] Write meta version 2 succeeds
I0614 11:53:39.046212 5389 MetaDaemon.cpp:168] Nebula store init succeeded, clusterId 5910483749134832870
I0614 11:53:39.046247 5389 MetaDaemon.cpp:259] Start http service
I0614 11:53:39.061019 5389 MetaDaemon.cpp:176] Starting Meta HTTP Service
I0614 11:53:39.082783 5502 WebService.cpp:131] Web service started on HTTP[19559], HTTP2[19560]
I0614 11:53:39.107321 5389 JobManager.cpp:52] JobManager initialized
I0614 11:53:39.107375 5389 MetaDaemon.cpp:290] Check and init root user
I0614 11:53:39.107506 5389 RootUserMan.h:28] Root user exists
I0614 11:53:39.107650 5509 JobManager.cpp:75] JobManager::runJobBackground() enter
I0614 11:53:39.125053 5389 MetaDaemon.cpp:316] The meta deamon start on "127.0.0.1":9559
I0614 11:54:35.938977 5517 CreateTagProcessor.cpp:70] Create Tag country, TagID 14
I0614 11:57:32.510231 5517 CreateEdgeProcessor.cpp:69] Create Edge state, edgeType 15
I0614 11:58:54.079912 5517 DropEdgeProcessor.cpp:53] Drop Edge state
I0614 11:59:16.352821 5517 CreateEdgeProcessor.cpp:69] Create Edge via, edgeType 16
I0614 11:59:20.976040 5517 SlowOpTracker.h:33] [Port: 9560, Space: 0, Part: 0] total time:79ms, Write WAL, total 2
I0614 11:59:28.249084 5517 DropEdgeProcessor.cpp:53] Drop Edge via
I0614 11:59:45.089385 5517 CreateEdgeProcessor.cpp:69] Create Edge in_state, edgeType 17
I0614 12:00:23.307042 5517 CreateTagProcessor.cpp:70] Create Tag state, TagID 18
I0614 12:01:32.131757 5517 DropEdgeProcessor.cpp:53] Drop Edge in_state
I0614 12:04:48.031153 5517 CreateEdgeProcessor.cpp:69] Create Edge via, edgeType 19
I0614 12:05:33.305068 5517 CreateTagProcessor.cpp:70] Create Tag city, TagID 20
I0614 12:23:24.722487 5517 SlowOpTracker.h:33] [Port: 9560, Space: 0, Part: 0] total time:584ms, Write WAL, total 2
I0614 12:24:26.813910 5430 SlowOpTracker.h:33] [Port: 9560, Space: 0, Part: 0] total time:51ms, Total send logs: 2
I0614 12:47:04.273929 5430 SlowOpTracker.h:33] [Port: 9560, Space: 0, Part: 0] total time:52ms, Total commit: 1
I0614 13:00:03.801821 5430 SlowOpTracker.h:33] [Port: 9560, Space: 0, Part: 0] total time:51ms, Total send logs: 2
I0614 13:33:03.154255 5517 CreateTagProcessor.cpp:70] Create Tag user, TagID 21
E0614 13:33:18.139430 5517 CreateTagProcessor.cpp:48] Create Tag Failed :story has existed
I0614 17:46:46.553503 5430 SlowOpTracker.h:33] [Port: 9560, Space: 0, Part: 0] total time:58ms, Total commit: 1
I0614 21:36:00.657276 5389 MetaDaemon.cpp:346] Signal 15(Terminated) received, stopping this server
I0614 21:36:00.657405 5389 JobManager.cpp:61] JobManager::shutDown() begin
I0614 21:36:00.659168 5509 JobManager.cpp:80] [JobManager] detect shutdown called, exit
E0614 21:36:00.681645 5509 JobDescription.cpp:188] Loading Job Description Failed
E0614 21:36:00.681777 5509 JobManager.cpp:88] [JobManager] load an invalid job from queue 0
I0614 21:36:00.681880 5389 JobManager.cpp:71] JobManager::shutDown() end
I0614 21:36:00.682057 5389 NebulaStore.cpp:33] Cut off the relationship with meta client
I0614 21:36:00.682103 5389 NebulaStore.cpp:35] Stop the raft service...
I0614 21:36:00.682144 5389 RaftexService.cpp:133] Stopping the raftex service on port 9560
I0614 21:36:00.682185 5389 RaftPart.cpp:352] [Port: 9560, Space: 0, Part: 0] Partition has been stopped
I0614 21:36:00.682224 5389 RaftexService.cpp:140] All partitions have stopped
I0614 21:36:00.682274 5389 NebulaStore.cpp:37] Waiting for the raft service stop...
I0614 21:36:00.682343 5443 RaftexService.cpp:114] The Raftex Service stopped
I0614 21:36:00.687747 5389 RaftexService.cpp:151] Server thread has stopped. Service on port 9560 is ready to be destroyed
I0614 21:36:00.687819 5389 RocksEngine.h:102] Release rocksdb on data/meta/nebula/0
I0614 21:36:00.703493 5389 NebulaStore.h:34] ~SpacePartInfo()
I0614 21:36:00.703853 5389 Part.h:38] [Port: 9560, Space: 0, Part: 0] ~Part()
I0614 21:36:00.703886 5389 RaftPart.cpp:254] [Port: 9560, Space: 0, Part: 0] The part has been destroyed...
I0614 21:36:01.075090 5389 FileBasedWal.cpp:83] [Port: 9560, Space: 0, Part: 0] ~FileBasedWal, dir = data/meta/nebula/0/wal/0
I0614 21:36:01.076031 5389 NebulaStore.cpp:43] ~NebulaStore()
I0614 21:36:01.077590 5389 MetaDaemon.cpp:328] The meta Daemon stopped
I0614 21:36:01.079337 5389 JobManager.cpp:61] JobManager::shutDown() begin
I0614 21:36:01.079380 5389 JobManager.cpp:63] JobManager not running, exit

@wey-gu
Copy link

wey-gu commented Jul 29, 2021

Dear @Robson96
sorry for the late response, from your metad Log: E0614 11:53:38.043859 5435 FileBasedWal.cpp:440] LogId is not consistent0 51440. The WAL logid==0 is inconsistent, which leads to the schema data not being properly synced. It may be caused due to some rare situation in laptop/virtual env, any chance you can redeploy the cluster in your MacBook and retry?

@Robson96
Copy link
Author

Robson96 commented Jul 30, 2021

the macbook i was testing is from 2007, and the error i thought was rare

@wey-gu
Copy link

wey-gu commented Jul 31, 2021

the macbook i was testing is from 2007, and the error I thought was rare

Aha, that makes sense considering the disk may be HDD and the CPU/RAM may not be that powerful back 2007.
BTW make old laptops into servers is brilliant! it's a server with both screen/wireless nic and UPS.

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
question Type: question about the product
Projects
None yet
Development

No branches or pull requests

3 participants